Minor feedback:
I noticed that after a roll-back following unsuccessful ArtifactCommit (e.g. if reboot happened after reboot from Mender), both rootfs partitions are named “primary” (for example when I insert SD card in my PC ). Even though it doesn’t break Mender, I wouldn’t call it expected behavior, some may base their scripts on partition names.
Here are the logs just in case:
update logs
2019-07-16 11:04:09 +0000 UTC info: Running Mender version 2.0.0
2019-07-16 11:04:09 +0000 UTC debug: handle update fetch state
2019-07-16 11:04:09 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:04:09 +0000 UTC debug: Received fetch update response &{200 OK 200 HTTP/1.1 1 1 map[Accept-Ranges:[bytes] Etag:[“b463fda25fa3a64bc6776860206364b8”] Server:[openresty/1.13.6.2] Content-Type:[application/vnd.mender-artifact] X-Amz-Request-Id:[15B1DE600A821B9C] X-Frame-Options:[DENY] X-Content-Type-Options:[nosniff] Date:[Tue, 16 Jul 2019 11:04:09 GMT] Vary:[Origin] X-Xss-Protection:[1; mode=block] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] Connection:[keep-alive] Content-Security-Policy:[block-all-mixed-content] Last-Modified:[Sat, 13 Jul 2019 11:26:36 GMT] Content-Length:[724988416]] 0x11dfc40 724988416 [] false false map[] 0x1088380 0x131c720}+
2019-07-16 11:04:09 +0000 UTC info: State transition: update-fetch [Download_Enter] -> update-store [Download_Enter]
2019-07-16 11:04:09 +0000 UTC debug: handle update install state
2019-07-16 11:04:09 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:04:09 +0000 UTC debug: Read data from device manifest file: device_type=raspberrypi3
2019-07-16 11:04:09 +0000 UTC debug: Current manifest data: raspberrypi3
2019-07-16 11:04:10 +0000 UTC info: installer: authenticated digital signature of artifact
2019-07-16 11:04:10 +0000 UTC debug: checking if device [raspberrypi3] is on compatibile device list: [raspberrypi3]
2019-07-16 11:04:10 +0000 UTC debug: installer: processing script: ArtifactInstall_Enter_01_retain_wpa_supplicant
2019-07-16 11:04:10 +0000 UTC debug: installer: successfully read artifact [name: test_hub_V2.0_raspbian04.2018_Scanner5.1.0-mender; version: 3; compatible devices: [raspberrypi3]]
2019-07-16 11:04:10 +0000 UTC debug: Trying to install update of size: 4097835008
2019-07-16 11:04:10 +0000 UTC debug: Active partition: /dev/mmcblk0p2
2019-07-16 11:04:10 +0000 UTC debug: Detected inactive partition /dev/mmcblk0p3, based on active partition /dev/mmcblk0p2
2019-07-16 11:04:10 +0000 UTC info: native sector size of block device /dev/mmcblk0p3 is 512, we will write in chunks of 1048576
2019-07-16 11:04:10 +0000 UTC info: opening device /dev/mmcblk0p3 for writing
2019-07-16 11:04:10 +0000 UTC info: partition /dev/mmcblk0p3 size: 4097835008
2019-07-16 11:29:47 +0000 UTC info: wrote 4097835008/4097835008 bytes of update to device /dev/mmcblk0p3
2019-07-16 11:29:48 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:29:48 +0000 UTC info: State transition: update-store [Download_Enter] -> update-after-store [Download_Leave]
2019-07-16 11:29:48 +0000 UTC info: State transition: update-after-store [Download_Leave] -> update-install [ArtifactInstall]
2019-07-16 11:29:48 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:29:48 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:29:48 +0000 UTC debug: start executing script: ArtifactInstall_Enter_01_retain_wpa_supplicant
2019-07-16 11:29:49 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:29:51 +0000 UTC error: stderr collected while running script /var/lib/mender/scripts/ArtifactInstall_Enter_01_retain_wpa_supplicant [artifact_name=hub_V2.0_raspbian04.2018_Scanner5.1.0: Running ArtifactInstall_Enter_01_retain_wpa_supplicant
]
2019-07-16 11:29:51 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:29:51 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:29:51 +0000 UTC debug: Inactive partition: /dev/mmcblk0p3
2019-07-16 11:29:51 +0000 UTC debug: Marking inactive partition (/dev/mmcblk0p3) as the new boot candidate.
2019-07-16 11:29:51 +0000 UTC info: Enabling partition with new image installed to be a boot candidate: 3
2019-07-16 11:29:51 +0000 UTC debug: Have U-Boot variable: mender_check_saveenv_canary=1
2019-07-16 11:29:51 +0000 UTC debug: List of U-Boot variables:map[mender_check_saveenv_canary:1]
2019-07-16 11:29:51 +0000 UTC debug: Have U-Boot variable: mender_saveenv_canary=1
2019-07-16 11:29:51 +0000 UTC debug: List of U-Boot variables:map[mender_saveenv_canary:1]
2019-07-16 11:29:51 +0000 UTC debug: Marking inactive partition as a boot candidate successful.
2019-07-16 11:29:51 +0000 UTC info: State transition: update-install [ArtifactInstall] -> reboot [ArtifactReboot_Enter]
2019-07-16 11:29:51 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:29:51 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:29:51 +0000 UTC debug: handling reboot state
2019-07-16 11:29:51 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:29:51 +0000 UTC info: rebooting device(s)
2019-07-16 11:29:51 +0000 UTC info: Mender rebooting from active partition: /dev/mmcblk0p2
2019-07-05 08:46:30 +0000 UTC info: Running Mender version 2.0.0
2019-07-05 08:46:30 +0000 UTC info: State transition: init [none] -> after-reboot [ArtifactReboot_Leave]
2019-07-05 08:46:30 +0000 UTC debug: Have U-Boot variable: mender_check_saveenv_canary=1
2019-07-05 08:46:30 +0000 UTC debug: List of U-Boot variables:map[mender_check_saveenv_canary:1]
2019-07-05 08:46:30 +0000 UTC debug: Have U-Boot variable: mender_saveenv_canary=1
2019-07-05 08:46:30 +0000 UTC debug: List of U-Boot variables:map[mender_saveenv_canary:1]
2019-07-05 08:46:30 +0000 UTC debug: Have U-Boot variable: upgrade_available=1
2019-07-05 08:46:30 +0000 UTC debug: List of U-Boot variables:map[upgrade_available:1]
2019-07-05 08:46:30 +0000 UTC info: State transition: after-reboot [ArtifactReboot_Leave] -> after-reboot [ArtifactReboot_Leave]
2019-07-05 08:46:30 +0000 UTC debug: handling state after reboot
2019-07-05 08:46:30 +0000 UTC info: State transition: after-reboot [ArtifactReboot_Leave] -> update-commit [ArtifactCommit_Enter]
2019-07-05 08:46:30 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-05 08:46:30 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-05 08:46:30 +0000 UTC debug: handle update commit state
2019-07-05 08:46:30 +0000 UTC error: failed to report status: Put https://mender.mydomain.io/api/devices/v1/deployments/device/deployments/b3a3513b-3892-46f6-9e00-e44834c1462f/status: dial tcp: lookup mender.mydomain.io on 192.168.1.1:53: dial udp 192.168.1.1:53: connect: network is unreachable
2019-07-05 08:46:30 +0000 UTC error: error reporting update status: reporting status failed: Put https://mender.mydomain.io/api/devices/v1/deployments/device/deployments/b3a3513b-3892-46f6-9e00-e44834c1462f/status: dial tcp: lookup mender.mydomain.io on 192.168.1.1:53: dial udp 192.168.1.1:53: connect: network is unreachable
2019-07-05 08:46:30 +0000 UTC error: Failed to send status report to server: transient error: reporting status failed: Put https://mender.mydomain.io/api/devices/v1/deployments/device/deployments/b3a3513b-3892-46f6-9e00-e44834c1462f/status: dial tcp: lookup mender.mydomain.io on 192.168.1.1:53: dial udp 192.168.1.1:53: connect: network is unreachable
2019-07-05 08:46:30 +0000 UTC info: State transition: update-commit [ArtifactCommit_Enter] -> update-pre-commit-status-report-retry [ArtifactCommit_Enter]
2019-07-16 11:30:02 +0000 UTC info: Running Mender version 2.0.0
2019-07-16 11:30:02 +0000 UTC error: Update was interrupted in state: update-commit
2019-07-16 11:30:02 +0000 UTC info: State transition: init [none] -> rollback [ArtifactRollback]
2019-07-16 11:30:02 +0000 UTC debug: transitioning to error state
2019-07-16 11:30:02 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:02 +0000 UTC info: performing rollback
2019-07-16 11:30:02 +0000 UTC debug: Have U-Boot variable: mender_check_saveenv_canary=1
2019-07-16 11:30:02 +0000 UTC debug: List of U-Boot variables:map[mender_check_saveenv_canary:1]
2019-07-16 11:30:02 +0000 UTC debug: Have U-Boot variable: mender_saveenv_canary=1
2019-07-16 11:30:02 +0000 UTC debug: List of U-Boot variables:map[mender_saveenv_canary:1]
2019-07-16 11:30:02 +0000 UTC debug: Have U-Boot variable: upgrade_available=0
2019-07-16 11:30:02 +0000 UTC debug: List of U-Boot variables:map[upgrade_available:0]
2019-07-16 11:30:02 +0000 UTC debug: will try to rollback reboot the device
2019-07-16 11:30:02 +0000 UTC info: State transition: rollback [ArtifactRollback] -> rollback-reboot [ArtifactRollbackReboot_Enter]
2019-07-16 11:30:02 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:02 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:02 +0000 UTC info: rebooting device(s) after rollback
2019-07-16 11:30:02 +0000 UTC info: Mender rebooting from inactive partition: /dev/mmcblk0p2
2019-07-16 11:30:17 +0000 UTC info: Running Mender version 2.0.0
2019-07-16 11:30:17 +0000 UTC info: Update was interrupted in state: rollback-reboot
2019-07-16 11:30:17 +0000 UTC info: State transition: init [none] -> verify-rollback-reboot [ArtifactRollbackReboot_Leave]
2019-07-16 11:30:17 +0000 UTC debug: transitioning to error state
2019-07-16 11:30:17 +0000 UTC debug: Have U-Boot variable: mender_check_saveenv_canary=1
2019-07-16 11:30:17 +0000 UTC debug: List of U-Boot variables:map[mender_check_saveenv_canary:1]
2019-07-16 11:30:17 +0000 UTC debug: Have U-Boot variable: mender_saveenv_canary=1
2019-07-16 11:30:17 +0000 UTC debug: List of U-Boot variables:map[mender_saveenv_canary:1]
2019-07-16 11:30:17 +0000 UTC debug: Have U-Boot variable: upgrade_available=0
2019-07-16 11:30:17 +0000 UTC debug: List of U-Boot variables:map[upgrade_available:0]
2019-07-16 11:30:17 +0000 UTC info: State transition: verify-rollback-reboot [ArtifactRollbackReboot_Leave] -> after-rollback-reboot [ArtifactRollbackReboot_Leave]
2019-07-16 11:30:17 +0000 UTC debug: handling state after rollback reboot
2019-07-16 11:30:17 +0000 UTC info: State transition: after-rollback-reboot [ArtifactRollbackReboot_Leave] -> update-error [ArtifactFailure]
2019-07-16 11:30:17 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:17 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:17 +0000 UTC debug: handle update error state
2019-07-16 11:30:17 +0000 UTC info: State transition: update-error [ArtifactFailure] -> cleanup [Error]
2019-07-16 11:30:17 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:17 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:17 +0000 UTC debug: Handling Cleanup state
2019-07-16 11:30:17 +0000 UTC info: State transition: cleanup [Error] -> update-status-report [none]
2019-07-16 11:30:17 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 1h0m0s seconds
2019-07-16 11:30:17 +0000 UTC debug: handle update status report state
2019-07-16 11:30:17 +0000 UTC error: failed to report status: Put https://mender.mydomain.io/api/devices/v1/deployments/device/deployments/b3a3513b-3892-46f6-9e00-e44834c1462f/status: dial tcp: lookup mender.mydomain.io on 192.168.1.1:53: dial udp 192.168.1.1:53: connect: network is unreachable
2019-07-16 11:30:17 +0000 UTC error: error reporting update status: reporting status failed: Put https://mender.mydomain.io/api/devices/v1/deployments/device/deployments/b3a3513b-3892-46f6-9e00-e44834c1462f/status: dial tcp: lookup mender.mydomain.io on 192.168.1.1:53: dial udp 192.168.1.1:53: connect: network is unreachable
2019-07-16 11:30:17 +0000 UTC error: failed to send status to server: transient error: reporting status failed: Put https://mender.mydomain.io/api/devices/v1/deployments/device/deployments/b3a3513b-3892-46f6-9e00-e44834c1462f/status: dial tcp: lookup mender.mydomain.io on 192.168.1.1:53: dial udp 192.168.1.1:53: connect: network is unreachable
2019-07-16 11:30:17 +0000 UTC info: State transition: update-status-report [none] -> update-retry-report [none]
2019-07-16 11:32:40 +0000 UTC debug: wait complete
2019-07-16 11:32:40 +0000 UTC info: State transition: update-retry-report [none] -> update-status-report [none]
2019-07-16 11:32:40 +0000 UTC debug: handle update status report state
2019-07-16 11:32:41 +0000 UTC debug: status reported, response 204 No Content
2019-07-16 11:32:41 +0000 UTC debug: attempting to upload deployment logs for failed update