"Pre-release" package for WP v5.9 shows up after manually checking for updates in Cloudron but never installs.

[jdaviescoates]

@d19dotca just fyi my wordpress apps on Cloudron all seem to have updated to the latest package fine. They seemingly updated to org.wordpress.unmanaged.cloudronapp@2.14.0 yesterday.

I've got auto-updates turned on within WordPress itself too, so I guess mine had already updated itself to 5.9 prior to the Cloudron app too.

[girish]

(WP update is still rolling out slowly and thus it might have auto-updated for some users and others still see it as unstable).

@d19dotca This behavior usually happens when the backup is failing. It tries to take a backup before an update, but since the backup failed, it rolls back. Currently, there is no obvious way to know if the backup failed and it aborted the update, other than looking at the logs. Can you start the update and check the app log viewer in parallel to see if something is failing?

[d19dotca]

@girish I don't think that's the case for me. I think I actually had this issue once before a few months ago I think and had to manually update them all, specifically for WordPress.

I just updated one successfully first try:

Feb 09 21:40:20 box:apphealthmonitor app health: 34 alive / 0 dead.
Feb 09 21:40:21 box:apps downloading manifest from https://api.cloudron.io/api/v1/apps/org.wordpress.unmanaged.cloudronapp/versions/2.14.0
Feb 09 21:40:21 box:locker Acquired : apptask
Feb 09 21:40:21 box:scheduler suspendJobs: 134ef7f1-46f8-403e-8ac5-a3297b37893f
Feb 09 21:40:21 box:tasks startTask - starting task 13991 with options {"timeout":72000000,"nice":15,"memoryLimit":4096,"logFile":"/home/yellowtent/platformdata/logs/134ef7f1-46f8-403e-8ac5-a3297b37893f/apptask.log"}. logs at /home/yellowtent/platformdata/logs/134ef7f1-46f8-403e-8ac5-a3297b37893f/apptask.log
Feb 09 21:40:21 box:shell startTask spawn: /usr/bin/sudo -S -E /home/yellowtent/box/src/scripts/starttask.sh 13991 /home/yellowtent/platformdata/logs/134ef7f1-46f8-403e-8ac5-a3297b37893f/apptask.log 15 4096
Feb 09 21:40:22 box:shell startTask (stdout): Running as unit: box-task-13991.service
Feb 09 21:40:29 box:cloudron Getting logs for box as json
Feb 09 21:40:30 box:apphealthmonitor app health: 33 alive / 1 dead.
Feb 09 21:40:40 box:apphealthmonitor app health: 33 alive / 1 dead.
Feb 09 21:40:45 box:shell startTask (stdout): Finished with result: success
processes terminated with: code=exited/status=0
runtime: 23.654s
Feb 09 21:40:45 box:shell startTask (stdout): Service box-task-13991 finished with exit code 0
Feb 09 21:40:45 box:tasks startTask: 13991 completed with code 0 and signal 0
Feb 09 21:40:45 box:apps scheduleTask: task 13991 of 134ef7f1-46f8-403e-8ac5-a3297b37893f completed
Feb 09 21:40:45 box:locker Released : apptask
Feb 09 21:40:45 box:scheduler resumeJobs: 134ef7f1-46f8-403e-8ac5-a3297b37893f
Feb 09 21:40:45 box:tasks startTask: 13991 done. error: undefined
Feb 09 21:40:50 box:apphealthmonitor setHealth: 134ef7f1-46f8-403e-8ac5-a3297b37893f (mainwp.<domain>) waiting for 1169.924 to update health
Feb 09 21:40:50 box:apphealthmonitor app health: 33 alive / 1 dead.
Feb 09 21:41:00 box:scheduler sync: adding jobs of 134ef7f1-46f8-403e-8ac5-a3297b37893f (mainwp.<domain>)
Feb 09 21:41:00 box:scheduler createJobs: wpcron (mainwp.<domain>) will run in container 134ef7f1-46f8-403e-8ac5-a3297b37893f-wpcron
Feb 09 21:41:00 box:apphealthmonitor app health: 34 alive / 0 dead.

My backups of all apps and email happen every night without fail too, for what that's worth (though I guess the backup for each app during update is a different task completely).

Other apps are working just fine to update automatically, it's only this WordPress one that seems to be failing for me.

[d19dotca]

I initially thought it had to do with it being marked pre-release still (as I thought pre-release updates to anything wasn't done automatically until graduated from pre-release), but I guess that's not the case here then since it updated for @jdaviescoates?

[girish]

@d19dotca said in "Pre-release" package for WP v5.9 shows up after manually checking for updates in Cloudron but never installs.:

/home/yellowtent/platformdata/logs/134ef7f1-46f8-403e-8ac5-a3297b37893f/apptask.log

You need to check the apptask.log file and not the box.log for the failed update (you have to replace the app id above).

[d19dotca]

@girish said in "Pre-release" package for WP v5.9 shows up after manually checking for updates in Cloudron but never installs.:

/home/yellowtent/platformdata/logs/134ef7f1-46f8-403e-8ac5-a3297b37893f/apptask.log

Ah, sorry I missed that one. I looked at it and see that it was successful at least when I did it manually:

2022-02-10T05:40:22.863Z box:settings initCache: pre-load settings
2022-02-10T05:40:22.884Z box:taskworker Starting task 13991. Logs are at /home/yellowtent/platformdata/logs/134ef7f1-46f8-403e-8ac5-a3297b37893f/apptask.log
2022-02-10T05:40:22.897Z box:apptask run: startTask installationState: pending_update runState: running
2022-02-10T05:40:22.898Z box:tasks update 13991: {"percent":5,"message":"Verify manifest"}
2022-02-10T05:40:22.917Z box:tasks update 13991: {"percent":15,"message":"Backing up app"}
2022-02-10T05:40:22.921Z box:backuptask backupApp: backing up mainwp.<domain> with tag 2022-02-10-054022-921
2022-02-10T05:40:22.923Z box:tasks update 13991: {"percent":15,"message":"Backup - Snapshotting app mainwp.<domain>"}
2022-02-10T05:40:22.929Z box:services backupAddons
2022-02-10T05:40:22.929Z box:services backupAddons: backing up ["mysql","ldap","localstorage","sendmail","scheduler","redis"]
2022-02-10T05:40:22.929Z box:services Backing up mysql
2022-02-10T05:40:24.000Z box:services Backing up redis
2022-02-10T05:40:24.030Z box:backuptask snapshotApp: mainwp.<domain> took 1.107 seconds
2022-02-10T05:40:24.031Z box:tasks update 13991: {"percent":15,"message":"Backup - Uploading app snapshot mainwp.<domain>"}
2022-02-10T05:40:24.032Z box:backuptask runBackupUpload: adjusting heap size to 3840M
2022-02-10T05:40:24.032Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f spawn: /usr/bin/sudo -S -E --close-from=4 /home/yellowtent/box/src/scripts/backupupload.js snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f tgz {"localRoot":"/home/yellowtent/appsdata/134ef7f1-46f8-403e-8ac5-a3297b37893f","layout":[]}
2022-02-10T05:40:24.748Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f (stdout): 2022-02-10T05:40:24.747Z box:backupupload Backing up {"localRoot":"/home/yellowtent/appsdata/134ef7f1-46f8-403e-8ac5-a3297b37893f","layout":[]} to snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f

2022-02-10T05:40:24.791Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f (stdout): 2022-02-10T05:40:24.790Z box:settings initCache: pre-load settings

2022-02-10T05:40:24.806Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f (stdout): 2022-02-10T05:40:24.806Z box:backupupload process: rss: 70.5MB heapUsed: 23.69MB heapTotal: 52.51MB external: 2.84MB
2022-02-10T05:40:24.806Z box:backupupload v8 heap: used 23.69MB total: 52.51MB max: 3.8GB

2022-02-10T05:40:24.807Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f (stdout): 2022-02-10T05:40:24.806Z box:backuptask upload: id snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f format tgz dataLayout {"localRoot":"/home/yellowtent/appsdata/134ef7f1-46f8-403e-8ac5-a3297b37893f","layout":[]}

2022-02-10T05:40:32.718Z box:tasks update 13991: {"percent":15,"message":"Backup - Uploading backup 25M@3MBps (mainwp.<domain>)"}
2022-02-10T05:40:36.730Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f (stdout): 2022-02-10T05:40:36.730Z box:storage/s3 Uploaded snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f.tar.gz with partSize 1073741824: {"ETag":"\"ddeaaaf69564e4b6356f29276bece3ea\"","Location":"https://cloudron-backups.s3.bhs.cloud.ovh.net/snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f.tar.gz","key":"snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f.tar.gz","Key":"snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f.tar.gz","Bucket":"cloudron-backups"}

2022-02-10T05:40:36.731Z box:shell backup-snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f (stdout): 2022-02-10T05:40:36.730Z box:backupupload upload completed. error:  null

2022-02-10T05:40:36.731Z box:backuptask runBackupUpload: result - {"result":""}
2022-02-10T05:40:36.749Z box:backuptask uploadAppSnapshot: mainwp.<domain> upload with id snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f. 12.718 seconds
2022-02-10T05:40:36.755Z box:backuptask rotateAppBackup: rotating mainwp.<domain> to id 2022-02-10-054022-921/app_mainwp.<domain>_v2.13.2
2022-02-10T05:40:37.076Z box:tasks update 13991: {"percent":15,"message":"Backup - Copying files from 0-1"}
2022-02-10T05:40:37.078Z box:tasks update 13991: {"percent":15,"message":"Backup - Copying snapshot/app_134ef7f1-46f8-403e-8ac5-a3297b37893f.tar.gz"}
2022-02-10T05:40:37.713Z box:tasks update 13991: {"percent":15,"message":"Backup - Copied 1 files with error: null"}
2022-02-10T05:40:37.718Z box:backuptask copy: copied successfully to id 2022-02-10-054022-921/app_mainwp.<domain>_v2.13.2. Took 0.959 seconds
2022-02-10T05:40:37.718Z box:tasks update 13991: {"percent":25,"message":"Downloading image"}
2022-02-10T05:40:37.762Z box:docker downloadImage cloudron/org.wordpress.unmanaged.cloudronapp:20220126-221636-3303660c1
2022-02-10T05:40:37.765Z box:docker downloadImage: pulling image. attempt 1
2022-02-10T05:40:37.765Z box:docker pullImage: will pull cloudron/org.wordpress.unmanaged.cloudronapp:20220126-221636-3303660c1. auth: no
2022-02-10T05:40:38.545Z box:docker pullImage: {"status":"Pulling from cloudron/org.wordpress.unmanaged.cloudronapp","id":"20220126-221636-3303660c1"}
2022-02-10T05:40:38.546Z box:docker pullImage: {"status":"Already exists","progressDetail":{},"id":"7b1a6ab2e44d"}
2022-02-10T05:40:38.551Z box:docker pullImage: {"status":"Already exists","progressDetail":{},"id":"d31a421b39ea"}
[…]
2022-02-10T05:40:38.593Z box:docker pullImage: {"status":"Waiting","progressDetail":{},"id":"7f518abb4117"}
2022-02-10T05:40:38.593Z box:docker pullImage: {"status":"Waiting","progressDetail":{},"id":"6a8510385600"}
2022-02-10T05:40:38.593Z box:docker pullImage: {"status":"Waiting","progressDetail":{},"id":"0b80ee8c7491"}
2022-02-10T05:40:38.593Z box:docker pullImage: {"status":"Waiting","progressDetail":{},"id":"46d3ff5bddf7"}
2022-02-10T05:40:38.593Z box:docker pullImage: {"status":"Waiting","progressDetail":{},"id":"41cd69556359"}
2022-02-10T05:40:38.593Z box:docker pullImage: {"status":"Waiting","progressDetail":{},"id":"261b23046879"}
2022-02-10T05:40:38.928Z box:docker pullImage: {"status":"Downloading","progressDetail":{"current":671,"total":3810},"progress":"[========>                                          ]     671B/3.81kB","id":"0d051b5db3a2"}
2022-02-10T05:40:38.928Z box:docker pullImage: {"status":"Downloading","progressDetail":{"current":3810,"total":3810},"progress":"[==================================================>]   3.81kB/3.81kB","id":"0d051b5db3a2"}
2022-02-10T05:40:38.928Z box:docker pullImage: {"status":"Verifying Checksum","progressDetail":{},"id":"0d051b5db3a2"}
2022-02-10T05:40:38.928Z box:docker pullImage: {"status":"Download complete","progressDetail":{},"id":"0d051b5db3a2"}
2022-02-10T05:40:38.928Z box:docker pullImage: {"status":"Extracting","progressDetail":{"current":3810,"total":3810},"progress":"[==================================================>]   3.81kB/3.81kB","id":"0d051b5db3a2"}
2022-02-10T05:40:38.935Z box:docker pullImage: {"status":"Extracting","progressDetail":{"current":3810,"total":3810},"progress":"[==================================================>]   3.81kB/3.81kB","id":"0d051b5db3a2"}
[…]
2022-02-10T05:40:41.997Z box:docker pullImage: {"status":"Extracting","progressDetail":{"current":911,"total":911},"progress":"[==================================================>]     911B/911B","id":"261b23046879"}
2022-02-10T05:40:42.114Z box:docker pullImage: {"status":"Pull complete","progressDetail":{},"id":"261b23046879"}
2022-02-10T05:40:42.117Z box:docker pullImage: {"status":"Extracting","progressDetail":{"current":210,"total":210},"progress":"[==================================================>]     210B/210B","id":"41cd69556359"}
2022-02-10T05:40:42.117Z box:docker pullImage: {"status":"Extracting","progressDetail":{"current":210,"total":210},"progress":"[==================================================>]     210B/210B","id":"41cd69556359"}
2022-02-10T05:40:42.265Z box:docker pullImage: {"status":"Pull complete","progressDetail":{},"id":"41cd69556359"}
2022-02-10T05:40:42.274Z box:docker pullImage: {"status":"Digest: sha256:bd7c2b9e21518dcc400c5840fb80f63faccc3bab07b7aa809790c8342636c996"}
2022-02-10T05:40:42.277Z box:docker pullImage: {"status":"Status: Downloaded newer image for cloudron/org.wordpress.unmanaged.cloudronapp:20220126-221636-3303660c1"}
2022-02-10T05:40:42.280Z box:docker downloaded image cloudron/org.wordpress.unmanaged.cloudronapp:20220126-221636-3303660c1
2022-02-10T05:40:42.280Z box:tasks update 13991: {"percent":35,"message":"Cleaning up old install"}
2022-02-10T05:40:42.285Z box:apptask deleteContainer: deleting app containers (app, scheduler)
2022-02-10T05:40:42.285Z box:shell removeCollectdProfile spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurecollectd.sh remove 134ef7f1-46f8-403e-8ac5-a3297b37893f
2022-02-10T05:40:42.302Z box:shell removeCollectdProfile (stdout): Restarting collectd

2022-02-10T05:40:42.429Z box:shell removeCollectdProfile (stdout): Removing collectd stats of 134ef7f1-46f8-403e-8ac5-a3297b37893f

2022-02-10T05:40:42.435Z box:shell removeLogrotateConfig spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurelogrotate.sh remove 134ef7f1-46f8-403e-8ac5-a3297b37893f
2022-02-10T05:40:43.005Z box:services teardownAddons: Tearing down []
2022-02-10T05:40:43.010Z box:tasks update 13991: {"percent":45,"message":"Downloading icon"}
2022-02-10T05:40:43.013Z box:apptask downloadIcon: Downloading icon of org.wordpress.unmanaged.cloudronapp@2.14.0
2022-02-10T05:40:43.195Z box:tasks update 13991: {"percent":60,"message":"Updating addons"}
2022-02-10T05:40:43.198Z box:services setupAddons: Setting up ["mysql","ldap","localstorage","sendmail","scheduler","redis"]
2022-02-10T05:40:43.198Z box:services setupAddons: setting up addon mysql with options {}
2022-02-10T05:40:43.198Z box:services Setting up mysql
2022-02-10T05:40:43.251Z box:services Setting mysql addon config to [{"name":"CLOUDRON_MYSQL_USERNAME","value":"a4b2000e709db53e"},{"name":"CLOUDRON_MYSQL_PASSWORD","value":"c6de8712228aae0f337925bcdb327e409ad6d766b2ba1fc9"},{"name":"CLOUDRON_MYSQL_HOST","value":"mysql"},{"name":"CLOUDRON_MYSQL_PORT","value":"3306"},{"name":"CLOUDRON_MYSQL_URL","value":"mysql://a4b2000e709db53e:c6de8712228aae0f337925bcdb327e409ad6d766b2ba1fc9@mysql/a4b2000e709db53e"},{"name":"CLOUDRON_MYSQL_DATABASE","value":"a4b2000e709db53e"}]
2022-02-10T05:40:43.257Z box:services setupAddons: setting up addon ldap with options {}
2022-02-10T05:40:43.257Z box:services Setting up LDAP
2022-02-10T05:40:43.266Z box:services setupAddons: setting up addon localstorage with options {"ftp":{"uid":33,"uname":"www-data"}}
2022-02-10T05:40:43.267Z box:services setupLocalStorage
2022-02-10T05:40:43.272Z box:shell createVolume spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/mkdirvolume.sh /home/yellowtent/appsdata/134ef7f1-46f8-403e-8ac5-a3297b37893f/data
2022-02-10T05:40:43.301Z box:services setupAddons: setting up addon sendmail with options {"optional":true}
2022-02-10T05:40:43.301Z box:services Setting up SendMail
2022-02-10T05:40:43.304Z box:services Setting sendmail addon config to [{"name":"CLOUDRON_MAIL_SMTP_SERVER","value":"mail"},{"name":"CLOUDRON_MAIL_SMTP_PORT","value":"2525"},{"name":"CLOUDRON_MAIL_SMTPS_PORT","value":"2465"},{"name":"CLOUDRON_MAIL_STARTTLS_PORT","value":"2587"},{"name":"CLOUDRON_MAIL_SMTP_USERNAME","value":"mainwp.app@<domain>"},{"name":"CLOUDRON_MAIL_SMTP_PASSWORD","value":"10f82337017514353451f5089d06fb067ee6771a685083a9"},{"name":"CLOUDRON_MAIL_FROM","value":"mainwp.app@<domain>"},{"name":"CLOUDRON_MAIL_DOMAIN","value":"<domain>"}]
2022-02-10T05:40:43.310Z box:services setupAddons: setting up addon scheduler with options {"wpcron":{"schedule":"*/1 * * * *","command":"/app/pkg/cron.sh"}}
2022-02-10T05:40:43.310Z box:services setupAddons: setting up addon redis with options {}
2022-02-10T05:40:43.326Z box:services Re-using existing redis container with state: {"Status":"running","Running":true,"Paused":false,"Restarting":false,"OOMKilled":false,"Dead":false,"Pid":4187,"ExitCode":0,"Error":"","StartedAt":"2022-02-04T07:08:53.994035786Z","FinishedAt":"2022-02-04T07:08:14.834903816Z"}
2022-02-10T05:40:43.338Z box:services Waiting for redis-134ef7f1-46f8-403e-8ac5-a3297b37893f
2022-02-10T05:40:43.817Z box:tasks update 13991: {"percent":70,"message":"Creating container"}
2022-02-10T05:40:43.820Z box:apptask createContainer: creating container
2022-02-10T05:40:43.900Z box:shell addLogrotateConfig spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurelogrotate.sh add 134ef7f1-46f8-403e-8ac5-a3297b37893f /tmp/134ef7f1-46f8-403e-8ac5-a3297b37893f.logrotate
2022-02-10T05:40:43.924Z box:shell addCollectdProfile spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurecollectd.sh add 134ef7f1-46f8-403e-8ac5-a3297b37893f
2022-02-10T05:40:43.942Z box:shell addCollectdProfile (stdout): Restarting collectd

2022-02-10T05:40:45.167Z box:apptask startApp: starting container
2022-02-10T05:40:45.686Z box:tasks update 13991: {"percent":90,"message":"Configuring reverse proxy"}
2022-02-10T05:40:45.696Z box:tasks update 13991: {"percent":100,"message":"Done"}
2022-02-10T05:40:45.704Z box:taskworker Task took 22.887 seconds
2022-02-10T05:40:45.704Z box:tasks setCompleted - 13991: {"result":null,"error":null}
2022-02-10T05:40:45.705Z box:tasks update 13991: {"percent":100,"result":null,"error":null}

I'm looking at the other ones that should have been automatic now and will report back shortly.

[d19dotca]

I don't see any logs from the same time unfortunately. The time should be "2022-02-10T07:00:00" for the automatic updates (it's 11 PM Pacific Time each night), but I see nothing in there. The latest log entries for another WP app that shows an update available but didn't install at the time of 11 PM tonight:

2022-02-09T06:18:03.642Z box:tasks update 13979: {"percent":30,"message":"Uploading backup 100M@3MBps (www.<domain>)"}
2022-02-09T06:18:10.472Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-09T06:18:10.472Z box:storage/s3 Uploaded snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz with partSize 1073741824: {"ETag":"\"887f98973a1033d49b0e9b621da953ae\"","Location":"https://cloudron-backups.s3.bhs.cloud.ovh.net/snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz","key":"snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz","Key":"snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz","Bucket":"cloudron-backups"}

2022-02-09T06:18:10.473Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-09T06:18:10.472Z box:backupupload upload completed. error:  null

2022-02-09T06:18:10.473Z box:backuptask runBackupUpload: result - {"result":""}
2022-02-09T06:18:10.515Z box:backuptask uploadAppSnapshot: www.<domain> upload with id snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7. 38.259 seconds
2022-02-09T06:18:10.524Z box:backuptask rotateAppBackup: rotating www.<domain> to id 2022-02-09-061731-357/app_www.<domain>_v2.13.2
2022-02-09T06:18:10.965Z box:tasks update 13979: {"percent":30,"message":"Copying files from 0-1"}
2022-02-09T06:18:10.967Z box:tasks update 13979: {"percent":30,"message":"Copying snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz"}
2022-02-09T06:18:13.285Z box:tasks update 13979: {"percent":30,"message":"Copied 1 files with error: null"}
2022-02-09T06:18:13.289Z box:backuptask copy: copied successfully to id 2022-02-09-061731-357/app_www.<domain>_v2.13.2. Took 2.734 seconds
2022-02-09T06:18:13.290Z box:tasks update 13979: {"percent":100,"message":"Done"}
2022-02-09T06:18:13.297Z box:taskworker Task took 42.022 seconds
2022-02-09T06:18:13.298Z box:tasks setCompleted - 13979: {"result":"2022-02-09-061731-357/app_www.<domain>_v2.13.2","error":null}
2022-02-09T06:18:13.298Z box:tasks update 13979: {"percent":100,"result":"2022-02-09-061731-357/app_www.<domain>_v2.13.2","error":null}

It seems the latest time stamp was the regular system backup time, approximately. It's as if it doesn't even see the update at all in the first place, at least not automatically. The update seems to only show if I do it manually for checking updates.

[d19dotca]

I just manually updated the same 7042fc52-0629-4c39-8fea-ba11370da8f7 app from above which didn't auto-update and the manual update was successful as the first one was. The logs are here:

2022-02-10T07:40:41.890Z box:settings initCache: pre-load settings
2022-02-10T07:40:41.911Z box:taskworker Starting task 13994. Logs are at /home/yellowtent/platformdata/logs/7042fc52-0629-4c39-8fea-ba11370da8f7/apptask.log
2022-02-10T07:40:41.926Z box:apptask run: startTask installationState: pending_update runState: running
2022-02-10T07:40:41.928Z box:tasks update 13994: {"percent":5,"message":"Verify manifest"}
2022-02-10T07:40:41.947Z box:tasks update 13994: {"percent":15,"message":"Backing up app"}
2022-02-10T07:40:41.950Z box:backuptask backupApp: backing up www.<domain> with tag 2022-02-10-074041-950
2022-02-10T07:40:41.952Z box:tasks update 13994: {"percent":15,"message":"Backup - Snapshotting app www.<domain>"}
2022-02-10T07:40:41.958Z box:services backupAddons
2022-02-10T07:40:41.958Z box:services backupAddons: backing up ["mysql","ldap","localstorage","sendmail","scheduler","redis"]
2022-02-10T07:40:41.958Z box:services Backing up mysql
2022-02-10T07:40:42.864Z box:services Backing up redis
2022-02-10T07:40:42.950Z box:backuptask snapshotApp: www.<domain> took 0.998 seconds
2022-02-10T07:40:42.950Z box:tasks update 13994: {"percent":15,"message":"Backup - Uploading app snapshot www.<domain>"}
2022-02-10T07:40:42.951Z box:backuptask runBackupUpload: adjusting heap size to 3840M
2022-02-10T07:40:42.951Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 spawn: /usr/bin/sudo -S -E --close-from=4 /home/yellowtent/box/src/scripts/backupupload.js snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 tgz {"localRoot":"/home/yellowtent/appsdata/7042fc52-0629-4c39-8fea-ba11370da8f7","layout":[]}
2022-02-10T07:40:43.704Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-10T07:40:43.702Z box:backupupload Backing up {"localRoot":"/home/yellowtent/appsdata/7042fc52-0629-4c39-8fea-ba11370da8f7","layout":[]} to snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7

2022-02-10T07:40:43.744Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-10T07:40:43.744Z box:settings initCache: pre-load settings

2022-02-10T07:40:43.761Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-10T07:40:43.761Z box:backupupload process: rss: 71.1MB heapUsed: 23.54MB heapTotal: 53.01MB external: 2.84MB
2022-02-10T07:40:43.761Z box:backupupload v8 heap: used 23.54MB total: 53.01MB max: 3.8GB

2022-02-10T07:40:43.762Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-10T07:40:43.761Z box:backuptask upload: id snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 format tgz dataLayout {"localRoot":"/home/yellowtent/appsdata/7042fc52-0629-4c39-8fea-ba11370da8f7","layout":[]}

2022-02-10T07:40:54.001Z box:tasks update 13994: {"percent":15,"message":"Backup - Uploading backup 62M@6MBps (www.<domain>)"}
2022-02-10T07:41:04.012Z box:tasks update 13994: {"percent":15,"message":"Backup - Uploading backup 91M@4MBps (www.<domain>)"}
2022-02-10T07:41:13.765Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-10T07:41:13.764Z box:backupupload process: rss: 425.2MB heapUsed: 22.03MB heapTotal: 62.34MB external: 201.39MB
2022-02-10T07:41:13.764Z box:backupupload v8 heap: used 22.03MB total: 62.34MB max: 3.8GB

2022-02-10T07:41:14.448Z box:shell backup-snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7 (stdout): 2022-02-10T07:41:14.446Z box:storage/s3 Uploaded snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz with partSize 1073741824: {"ETag":"\"afe7224197e6ce11708302fe1492c582\"","Location":"https://cloudron-backups.s3.bhs.cloud.ovh.net/snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz","key":"snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz","Key":"snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz","Bucket":"cloudron-backups"}
2022-02-10T07:41:14.447Z box:backupupload upload completed. error:  null

2022-02-10T07:41:14.448Z box:backuptask runBackupUpload: result - {"result":""}
2022-02-10T07:41:14.475Z box:backuptask uploadAppSnapshot: www.<domain> upload with id snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7. 31.524 seconds
2022-02-10T07:41:14.481Z box:backuptask rotateAppBackup: rotating www.<domain> to id 2022-02-10-074041-950/app_www.<domain>_v2.13.2
2022-02-10T07:41:14.913Z box:tasks update 13994: {"percent":15,"message":"Backup - Copying files from 0-1"}
2022-02-10T07:41:14.915Z box:tasks update 13994: {"percent":15,"message":"Backup - Copying snapshot/app_7042fc52-0629-4c39-8fea-ba11370da8f7.tar.gz"}
2022-02-10T07:41:17.679Z box:tasks update 13994: {"percent":15,"message":"Backup - Copied 1 files with error: null"}
2022-02-10T07:41:17.682Z box:backuptask copy: copied successfully to id 2022-02-10-074041-950/app_www.<domain>_v2.13.2. Took 3.196 seconds
2022-02-10T07:41:17.683Z box:tasks update 13994: {"percent":25,"message":"Downloading image"}
2022-02-10T07:41:17.724Z box:docker downloadImage cloudron/org.wordpress.unmanaged.cloudronapp:20220126-221636-3303660c1
2022-02-10T07:41:17.732Z box:tasks update 13994: {"percent":35,"message":"Cleaning up old install"}
2022-02-10T07:41:17.736Z box:apptask deleteContainer: deleting app containers (app, scheduler)
2022-02-10T07:41:17.736Z box:shell removeCollectdProfile spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurecollectd.sh remove 7042fc52-0629-4c39-8fea-ba11370da8f7
2022-02-10T07:41:17.754Z box:shell removeCollectdProfile (stdout): Restarting collectd

2022-02-10T07:41:17.838Z box:shell removeCollectdProfile (stdout): Removing collectd stats of 7042fc52-0629-4c39-8fea-ba11370da8f7

2022-02-10T07:41:17.843Z box:shell removeLogrotateConfig spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurelogrotate.sh remove 7042fc52-0629-4c39-8fea-ba11370da8f7
2022-02-10T07:41:18.331Z box:services teardownAddons: Tearing down []
2022-02-10T07:41:18.336Z box:tasks update 13994: {"percent":45,"message":"Downloading icon"}
2022-02-10T07:41:18.338Z box:apptask downloadIcon: Downloading icon of org.wordpress.unmanaged.cloudronapp@2.14.0
2022-02-10T07:41:18.529Z box:tasks update 13994: {"percent":60,"message":"Updating addons"}
2022-02-10T07:41:18.531Z box:services setupAddons: Setting up ["mysql","ldap","localstorage","sendmail","scheduler","redis"]
2022-02-10T07:41:18.532Z box:services setupAddons: setting up addon mysql with options {}
2022-02-10T07:41:18.532Z box:services Setting up mysql
2022-02-10T07:41:18.591Z box:services Setting mysql addon config to [{"name":"CLOUDRON_MYSQL_USERNAME","value":"b3f36100b9cb1d5c"},{"name":"CLOUDRON_MYSQL_PASSWORD","value":"53e4c039cea62ecf31cc01f244c8cb57a97fb9f95aec118e"},{"name":"CLOUDRON_MYSQL_HOST","value":"mysql"},{"name":"CLOUDRON_MYSQL_PORT","value":"3306"},{"name":"CLOUDRON_MYSQL_URL","value":"mysql://b3f36100b9cb1d5c:53e4c039cea62ecf31cc01f244c8cb57a97fb9f95aec118e@mysql/b3f36100b9cb1d5c"},{"name":"CLOUDRON_MYSQL_DATABASE","value":"b3f36100b9cb1d5c"}]
2022-02-10T07:41:18.595Z box:services setupAddons: setting up addon ldap with options {}
2022-02-10T07:41:18.596Z box:services Setting up LDAP
2022-02-10T07:41:18.601Z box:services setupAddons: setting up addon localstorage with options {"ftp":{"uid":33,"uname":"www-data"}}
2022-02-10T07:41:18.601Z box:services setupLocalStorage
2022-02-10T07:41:18.610Z box:shell createVolume spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/mkdirvolume.sh /home/yellowtent/appsdata/7042fc52-0629-4c39-8fea-ba11370da8f7/data
2022-02-10T07:41:18.631Z box:services setupAddons: setting up addon sendmail with options {"optional":true}
2022-02-10T07:41:18.632Z box:services Setting up SendMail
2022-02-10T07:41:18.634Z box:services Setting sendmail addon config to [{"name":"CLOUDRON_MAIL_SMTP_SERVER","value":"mail"},{"name":"CLOUDRON_MAIL_SMTP_PORT","value":"2525"},{"name":"CLOUDRON_MAIL_SMTPS_PORT","value":"2465"},{"name":"CLOUDRON_MAIL_STARTTLS_PORT","value":"2587"},{"name":"CLOUDRON_MAIL_SMTP_USERNAME","value":"www.app@<domain>"},{"name":"CLOUDRON_MAIL_SMTP_PASSWORD","value":"b7bdc1a9a985dc0e9235bcf3d6795a742cb5d045435aaf04"},{"name":"CLOUDRON_MAIL_FROM","value":"www.app@<domain>"},{"name":"CLOUDRON_MAIL_DOMAIN","value":"<domain>"}]
2022-02-10T07:41:18.638Z box:services setupAddons: setting up addon scheduler with options {"wpcron":{"schedule":"*/1 * * * *","command":"/app/pkg/cron.sh"}}
2022-02-10T07:41:18.638Z box:services setupAddons: setting up addon redis with options {}
2022-02-10T07:41:18.650Z box:services Re-using existing redis container with state: {"Status":"running","Running":true,"Paused":false,"Restarting":false,"OOMKilled":false,"Dead":false,"Pid":1674,"ExitCode":0,"Error":"","StartedAt":"2022-02-10T06:04:08.479502461Z","FinishedAt":"2022-02-10T06:03:37.869462721Z"}
2022-02-10T07:41:18.655Z box:services Waiting for redis-7042fc52-0629-4c39-8fea-ba11370da8f7
2022-02-10T07:41:18.964Z box:tasks update 13994: {"percent":70,"message":"Creating container"}
2022-02-10T07:41:18.968Z box:apptask createContainer: creating container
2022-02-10T07:41:19.077Z box:shell addLogrotateConfig spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurelogrotate.sh add 7042fc52-0629-4c39-8fea-ba11370da8f7 /tmp/7042fc52-0629-4c39-8fea-ba11370da8f7.logrotate
2022-02-10T07:41:19.100Z box:shell addCollectdProfile spawn: /usr/bin/sudo -S /home/yellowtent/box/src/scripts/configurecollectd.sh add 7042fc52-0629-4c39-8fea-ba11370da8f7
2022-02-10T07:41:19.116Z box:shell addCollectdProfile (stdout): Restarting collectd

2022-02-10T07:41:20.293Z box:apptask startApp: starting container
2022-02-10T07:41:20.848Z box:tasks update 13994: {"percent":90,"message":"Configuring reverse proxy"}
2022-02-10T07:41:20.852Z box:tasks update 13994: {"percent":100,"message":"Done"}
2022-02-10T07:41:20.868Z box:taskworker Task took 39.036 seconds
2022-02-10T07:41:20.868Z box:tasks setCompleted - 13994: {"result":null,"error":null}
2022-02-10T07:41:20.868Z box:tasks update 13994: {"percent":100,"result":null,"error":null}

[girish]

@d19dotca quite strange, atleast the logs don't show an error. Let me know if you are not able to update other instances.

[d19dotca]

@girish None of the other WordPress instances will auto-update, but they all update fine if I do it manually. I left the others for now in case you need to troubleshoot the issue as it is currently.

[d19dotca]

@girish - It seemed like tonight it finally updated. Not sure why tonight over any other night though when it's been out for about 2 weeks and I check for auto-updates M-F each night, but it looks good now.