2018-08-08 10:05:00 +0000 UTC debug: handle update fetch state
2018-08-08 10:05:01 +0000 UTC debug: status reported, response &{204 No Content 204 HTTP/2.0 2 0 map[X-Xss-Protection:[1; mode=block] Content-Encoding:[gzip] Vary:[Accept-Encoding] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Frame-Options:[DENY] X-Content-Type-Options:[nosniff] Pragma:[no-cache] Server:[openresty/1.13.6.2] Date:[Wed, 08 Aug 2018 10:05:01 GMT] X-Deployments-Version:[unknown] X-Men-Requestid:[6a809d98-7746-408b-beb9-82ef31fd57ee] Cache-Control:[no-cache, no-store]] 0x10f50010 0 [] false false map[] 0x10da2480 0x10e300c0}
2018-08-08 10:05:01 +0000 UTC debug: Received fetch update response &{200 OK 200 HTTP/1.1 1 1 map[Content-Length:[175606272] Accept-Ranges:[bytes] Last-Modified:[Wed, 08 Aug 2018 10:03:34 GMT] Server:[openresty/1.11.2.2] Connection:[keep-alive] Vary:[Origin] Content-Type:[application/vnd.mender-artifact] X-Frame-Options:[DENY] X-Content-Type-Options:[nosniff] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] Etag:["6c5880bdd95e4c8b540deef06b198755"] X-Amz-Request-Id:[3QW2FZVMY5IYB6L2] Date:[Wed, 08 Aug 2018 10:05:01 GMT]] 0x10f501b0 175606272 [] false false map[] 0x10da2280 0x10de2720}+
2018-08-08 10:05:01 +0000 UTC info: State transition: update-fetch [Download] -> update-store [Download]
2018-08-08 10:05:01 +0000 UTC debug: handle update install state
2018-08-08 10:05:01 +0000 UTC debug: status reported, response &{204 No Content 204 HTTP/2.0 2 0 map[Date:[Wed, 08 Aug 2018 10:05:01 GMT] Content-Encoding:[gzip] Vary:[Accept-Encoding] X-Men-Requestid:[671d4d10-8b25-4f76-905b-59f8abe53ba8] X-Content-Type-Options:[nosniff] Server:[openresty/1.13.6.2] X-Deployments-Version:[unknown] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Frame-Options:[DENY] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Pragma:[no-cache]] 0x10f503b0 0 [] false false map[] 0x10da2500 0x10e300c0}
2018-08-08 10:05:01 +0000 UTC debug: Read data from device manifest file: device_type=raspberrypi3
2018-08-08 10:05:01 +0000 UTC debug: Found needed line: device_type=raspberrypi3
2018-08-08 10:05:01 +0000 UTC debug: Current manifest data: raspberrypi3
2018-08-08 10:05:01 +0000 UTC debug: checking if device [raspberrypi3] is on compatibile device list: [raspberrypi3]
2018-08-08 10:05:01 +0000 UTC debug: installer: processing script: ArtifactCommit_Enter_10_wait-for-timesync
2018-08-08 10:05:01 +0000 UTC debug: installing update rpi-basic-image-raspberrypi3.ext4 of size 973078528
2018-08-08 10:05:01 +0000 UTC debug: Trying to install update of size: 973078528
2018-08-08 10:05:01 +0000 UTC debug: Have U-Boot variable: mender_boot_part=3
2018-08-08 10:05:01 +0000 UTC debug: List of U-Boot variables:map[mender_boot_part:3]
2018-08-08 10:05:01 +0000 UTC debug: Setting active partition from mount candidate: /dev/mmcblk0p3
2018-08-08 10:05:01 +0000 UTC debug: Detected inactive partition /dev/mmcblk0p2, based on active partition /dev/mmcblk0p3
2018-08-08 10:05:01 +0000 UTC info: opening device /dev/mmcblk0p2 for writing
2018-08-08 10:05:01 +0000 UTC info: partition /dev/mmcblk0p2 size: 973078528
2018-08-08 10:13:53 +0000 UTC info: wrote 973078528/973078528 bytes of update to device /dev/mmcblk0p2
2018-08-08 10:13:55 +0000 UTC debug: installer: successfully read artifact [name: develop-134; version: 2; compatible devices: [raspberrypi3]]
2018-08-08 10:13:55 +0000 UTC debug: status reported, response &{204 No Content 204 HTTP/2.0 2 0 map[X-Frame-Options:[DENY] Server:[openresty/1.13.6.2] Date:[Wed, 08 Aug 2018 10:13:55 GMT] Content-Encoding:[gzip] Vary:[Accept-Encoding] X-Men-Requestid:[2ba975b5-c9e0-41bd-ab46-48d95e6c64f1] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Deployments-Version:[unknown] X-Content-Type-Options:[nosniff] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Pragma:[no-cache]] 0x10f50040 0 [] false false map[] 0x10da2400 0x10e30360}
2018-08-08 10:13:55 +0000 UTC info: State transition: update-store [Download] -> update-install [ArtifactInstall]
2018-08-08 10:13:55 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:13:55 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:13:55 +0000 UTC debug: status reported, response &{204 No Content 204 HTTP/2.0 2 0 map[X-Men-Requestid:[b056c412-387d-4eb2-bac2-c7beaae868e9] X-Content-Type-Options:[nosniff] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Date:[Wed, 08 Aug 2018 10:13:55 GMT] Content-Encoding:[gzip] Vary:[Accept-Encoding] X-Deployments-Version:[unknown] Pragma:[no-cache] Server:[openresty/1.13.6.2] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Frame-Options:[DENY]] 0x10f7e000 0 [] false false map[] 0x10e02100 0x10e30360}
2018-08-08 10:13:55 +0000 UTC debug: Inactive partition: /dev/mmcblk0p2
2018-08-08 10:13:55 +0000 UTC debug: Marking inactive partition (/dev/mmcblk0p2) as the new boot candidate.
2018-08-08 10:13:55 +0000 UTC info: Enabling partition with new image installed to be a boot candidate: 2
2018-08-08 10:13:55 +0000 UTC debug: Marking inactive partition as a boot candidate successful.
2018-08-08 10:13:55 +0000 UTC info: State transition: update-install [ArtifactInstall] -> reboot [ArtifactReboot_Enter]
2018-08-08 10:13:55 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:13:55 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:13:55 +0000 UTC debug: handling reboot state
2018-08-08 10:13:55 +0000 UTC debug: status reported, response &{204 No Content 204 HTTP/2.0 2 0 map[X-Deployments-Version:[unknown] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Xss-Protection:[1; mode=block] Server:[openresty/1.13.6.2] Date:[Wed, 08 Aug 2018 10:13:55 GMT] Content-Encoding:[gzip] X-Content-Type-Options:[nosniff] Cache-Control:[no-cache, no-store] Pragma:[no-cache] Vary:[Accept-Encoding] X-Men-Requestid:[f4b63c58-2e8e-4a03-83d5-7f56e43f0cd3] X-Frame-Options:[DENY]] 0x10f50290 0 [] false false map[] 0x10ef4100 0x10e30360}
2018-08-08 10:13:55 +0000 UTC info: rebooting device
2018-08-08 10:34:39 +0000 UTC debug: handling state after reboot
2018-08-08 10:34:39 +0000 UTC info: State transition: after-reboot [ArtifactReboot_Leave] -> update-verify [ArtifactReboot_Leave]
2018-08-08 10:34:39 +0000 UTC debug: handle update verify state
2018-08-08 10:34:39 +0000 UTC debug: Have U-Boot variable: upgrade_available=0
2018-08-08 10:34:39 +0000 UTC debug: List of U-Boot variables:map[upgrade_available:0]
2018-08-08 10:34:39 +0000 UTC error: update info for deployment d600e01c-d4e5-4adf-a34a-e0e707577ebe present, but update flag is not set; running rollback image (previous active partition)
2018-08-08 10:34:39 +0000 UTC info: State transition: update-verify [ArtifactReboot_Leave] -> rollback [ArtifactRollback]
2018-08-08 10:34:39 +0000 UTC debug: transitioning to error state
2018-08-08 10:34:39 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:34:39 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:34:39 +0000 UTC info: performing rollback
2018-08-08 10:34:39 +0000 UTC info: State transition: rollback [ArtifactRollback] -> update-error [ArtifactFailure]
2018-08-08 10:34:39 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:34:39 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-08 10:34:39 +0000 UTC debug: handle update error state
2018-08-08 10:34:39 +0000 UTC info: State transition: update-error [ArtifactFailure] -> update-status-report [none]
2018-08-08 10:34:39 +0000 UTC debug: handle update status report state
2018-08-08 10:34:39 +0000 UTC debug: status reported, response &{204 No Content 204 HTTP/2.0 2 0 map[Server:[openresty/1.13.6.2] Date:[Wed, 08 Aug 2018 10:34:39 GMT] X-Deployments-Version:[unknown] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Frame-Options:[DENY] X-Content-Type-Options:[nosniff] Content-Encoding:[gzip] Vary:[Accept-Encoding] X-Men-Requestid:[9b7ab8d9-80bc-4055-b783-6f09b88872ad] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Pragma:[no-cache]] 0x123565b0 0 [] false false map[] 0x121c2280 0x12202780}
2018-08-08 10:34:39 +0000 UTC debug: attempting to upload deployment logs for failed update