gistfile1.txt

2018-08-28 22:41:42 +0000 UTC debug: handle update fetch state
2018-08-28 22:41:43 +0000 UTC debug: status reported, response &{204 No Content %!s(int=204) HTTP/2.0 %!s(int=2) %!s(int=0) map[Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Deployments-Version:[unknown] X-Men-Requestid:[81f9198d-d235-48c7-b357-b60de766bdf9] Content-Encoding:[gzip] Vary:[Accept-Encoding] X-Frame-Options:[DENY] X-Content-Type-Options:[nosniff] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Server:[openresty/1.13.6.2] Date:[Tue, 28 Aug 2018 22:41:43 GMT] Pragma:[no-cache]] %!s(*http.cancelTimerBody=&{0x1d687c {0x10970b20} 0x1ddfc4}) %!s(int64=0) [] %!s(bool=false) %!s(bool=false) map[] %!s(*http.Request=&{PUT 0x109110c0 HTTP/1.1 1 1 map[Content-Type:[application/json] Authorization:[Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE1MzYwMzI5MjgsImp0aSI6Ijk2ODk0MTMzLWQ0MDktNDU3Ny04MTBhLTlkMDA0ZjNlNjUxYiIsImlzcyI6Ik1lbmRlciIsInN1YiI6IjViODRjNDdiOGRhNTk2MDAwMWUxMzMxYSIsIm1lbmRlci5kZXZpY2UiOnRydWV9.ZH7RefvYo7cNJtDPb5g5qNG_06vgE5pTCd7FdChC0vFle0WgsNJwIjO_jYEVnEyk7uhcBZyf7eHAqucX3mm-xnnfBMrGO1DEwdg_13e_jwl-6zmBcgG-PueJ2wFLw8YSpdCeIg3FdvkuObJww5qRq9LOAZcajHrzbZMzNttOzi3jC-t05KSNjt-2Gu5H6Slz6PNjccyEv_OHuizbtpCMGbXp3or8aLUJ0qtR83_FKPouW-WN_jraKad2p9z7tyqGWnshgSRAKxmT1450WS5JOIDTi6KUIeDwAUSStvsSX0Gn4Tq8exi9ZXSnqP0Y5nQpwso3a_35kSybOJbf8V8tZ-e22Lx03JCD2mmKIZsNQiACiqsUqjvoQdkkjm6hi8t3gW7U3cBBezBuYo0cPeYFd4g2qBdsOasPmBJLY5FPKktfOka1nsBGL5dWxB-Qd5oMsYMXNSLayRfZ3yx-Iev89s3JlKgAxkL6YMV4JY-truM5d4M6aC6rpCFcNOv75ZgD]] {0x10cde720} 0x1d8020 25 [] false 104.197.247.211 map[] map[] <nil> map[]   <nil> 0x10cea800 <nil> <nil>}) %!s(*tls.ConnectionState=&{771 true false 49196 h2 true  [0x109b0000] [] [] [] [94 26 44 205 59 42 86 93 15 189 138 172]})}
2018-08-28 22:41:43 +0000 UTC debug: Received fetch update response &{200 OK 200 HTTP/1.1 1 1 map[Server:[openresty/1.11.2.2] Date:[Tue, 28 Aug 2018 22:41:43 GMT] Accept-Ranges:[bytes] X-Content-Type-Options:[nosniff] Content-Length:[90847232] X-Frame-Options:[DENY] Content-Type:[application/vnd.mender-artifact] Connection:[keep-alive] Vary:[Origin] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] Etag:["85950d5c36999443e9b1b7a2c541eefa"] Last-Modified:[Tue, 28 Aug 2018 16:22:39 GMT] X-Amz-Request-Id:[EZBOW0POXZGXO0ZC]] 0x10b2e480 90847232 [] false false map[] 0x109a2400 0x109e2cc0}+
2018-08-28 22:41:43 +0000 UTC info: State transition: update-fetch [Download] -> update-store [Download]
2018-08-28 22:41:43 +0000 UTC debug: handle update install state
2018-08-28 22:41:43 +0000 UTC debug: status reported, response &{204 No Content %!s(int=204) HTTP/2.0 %!s(int=2) %!s(int=0) map[Cache-Control:[no-cache, no-store] Date:[Tue, 28 Aug 2018 22:41:43 GMT] X-Deployments-Version:[unknown] X-Men-Requestid:[de33492f-7aba-434b-95e4-7a76335d3bda] X-Content-Type-Options:[nosniff] X-Frame-Options:[DENY] X-Xss-Protection:[1; mode=block] Pragma:[no-cache] Server:[openresty/1.13.6.2] Content-Encoding:[gzip] Vary:[Accept-Encoding] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload]] %!s(*http.cancelTimerBody=&{0x1d687c {0x10970b20} 0x1ddfc4}) %!s(int64=0) [] %!s(bool=false) %!s(bool=false) map[] %!s(*http.Request=&{PUT 0x10911300 HTTP/1.1 1 1 map[Content-Type:[application/json] Authorization:[Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE1MzYwMzI5MjgsImp0aSI6Ijk2ODk0MTMzLWQ0MDktNDU3Ny04MTBhLTlkMDA0ZjNlNjUxYiIsImlzcyI6Ik1lbmRlciIsInN1YiI6IjViODRjNDdiOGRhNTk2MDAwMWUxMzMxYSIsIm1lbmRlci5kZXZpY2UiOnRydWV9.ZH7RefvYo7cNJtDPb5g5qNG_06vgE5pTCd7FdChC0vFle0WgsNJwIjO_jYEVnEyk7uhcBZyf7eHAqucX3mm-xnnfBMrGO1DEwdg_13e_jwl-6zmBcgG-PueJ2wFLw8YSpdCeIg3FdvkuObJww5qRq9LOAZcajHrzbZMzNttOzi3jC-t05KSNjt-2Gu5H6Slz6PNjccyEv_OHuizbtpCMGbXp3or8aLUJ0qtR83_FKPouW-WN_jraKad2p9z7tyqGWnshgSRAKxmT1450WS5JOIDTi6KUIeDwAUSStvsSX0Gn4Tq8exi9ZXSnqP0Y5nQpwso3a_35kSybOJbf8V8tZ-e22Lx03JCD2mmKIZsNQiACiqsUqjvoQdkkjm6hi8t3gW7U3cBBezBuYo0cPeYFd4g2qBdsOasPmBJLY5FPKktfOka1nsBGL5dWxB-Qd5oMsYMXNSLayRfZ3yx-Iev89s3JlKgAxkL6YMV4JY-truM5d4M6aC6rpCFcNOv75ZgD]] {0x10cde9c0} 0x1d8020 25 [] false 104.197.247.211 map[] map[] <nil> map[]   <nil> 0x10cea980 <nil> <nil>}) %!s(*tls.ConnectionState=&{771 true false 49196 h2 true  [0x109b0000] [] [] [] [94 26 44 205 59 42 86 93 15 189 138 172]})}
2018-08-28 22:41:43 +0000 UTC debug: Read data from device manifest file: device_type=raspberrypi3
2018-08-28 22:41:43 +0000 UTC debug: Found needed line: device_type=raspberrypi3
2018-08-28 22:41:43 +0000 UTC debug: Current manifest data: raspberrypi3
2018-08-28 22:41:43 +0000 UTC debug: checking if device [raspberrypi3] is on compatibile device list: [raspberrypi3]
2018-08-28 22:41:43 +0000 UTC debug: installer: processing script: ArtifactCommit_Enter_10_wait-for-timesync
2018-08-28 22:41:43 +0000 UTC debug: installing update gcp-mender-demo-image-raspberrypi3.ext4 of size 1015021568
2018-08-28 22:41:43 +0000 UTC debug: Trying to install update of size: 1015021568
2018-08-28 22:41:43 +0000 UTC debug: Have U-Boot variable: mender_boot_part=2
2018-08-28 22:41:43 +0000 UTC debug: List of U-Boot variables:map[mender_boot_part:2]
2018-08-28 22:41:43 +0000 UTC debug: Setting active partition from mount candidate: /dev/mmcblk0p2
2018-08-28 22:41:43 +0000 UTC debug: Detected inactive partition /dev/mmcblk0p3, based on active partition /dev/mmcblk0p2
2018-08-28 22:41:43 +0000 UTC info: opening device /dev/mmcblk0p3 for writing
2018-08-28 22:41:43 +0000 UTC info: partition /dev/mmcblk0p3 size: 1015021568
2018-08-28 22:50:30 +0000 UTC info: wrote 1015021568/1015021568 bytes of update to device /dev/mmcblk0p3
2018-08-28 22:50:35 +0000 UTC debug: installer: successfully read artifact [name: release-2; version: 2; compatible devices: [raspberrypi3]]
2018-08-28 22:50:36 +0000 UTC debug: status reported, response &{204 No Content %!s(int=204) HTTP/2.0 %!s(int=2) %!s(int=0) map[Cache-Control:[no-cache, no-store] Pragma:[no-cache] Server:[openresty/1.13.6.2] Date:[Tue, 28 Aug 2018 22:50:36 GMT] Vary:[Accept-Encoding] X-Deployments-Version:[unknown] X-Men-Requestid:[86a2be56-baea-46f8-ac75-c19cb45afced] X-Content-Type-Options:[nosniff] Content-Encoding:[gzip] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Frame-Options:[DENY] X-Xss-Protection:[1; mode=block]] %!s(*http.cancelTimerBody=&{0x1d687c {0x10970b20} 0x1ddfc4}) %!s(int64=0) [] %!s(bool=false) %!s(bool=false) map[] %!s(*http.Request=&{PUT 0x1096e800 HTTP/1.1 1 1 map[Content-Type:[application/json] Authorization:[Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE1MzYwMzI5MjgsImp0aSI6Ijk2ODk0MTMzLWQ0MDktNDU3Ny04MTBhLTlkMDA0ZjNlNjUxYiIsImlzcyI6Ik1lbmRlciIsInN1YiI6IjViODRjNDdiOGRhNTk2MDAwMWUxMzMxYSIsIm1lbmRlci5kZXZpY2UiOnRydWV9.ZH7RefvYo7cNJtDPb5g5qNG_06vgE5pTCd7FdChC0vFle0WgsNJwIjO_jYEVnEyk7uhcBZyf7eHAqucX3mm-xnnfBMrGO1DEwdg_13e_jwl-6zmBcgG-PueJ2wFLw8YSpdCeIg3FdvkuObJww5qRq9LOAZcajHrzbZMzNttOzi3jC-t05KSNjt-2Gu5H6Slz6PNjccyEv_OHuizbtpCMGbXp3or8aLUJ0qtR83_FKPouW-WN_jraKad2p9z7tyqGWnshgSRAKxmT1450WS5JOIDTi6KUIeDwAUSStvsSX0Gn4Tq8exi9ZXSnqP0Y5nQpwso3a_35kSybOJbf8V8tZ-e22Lx03JCD2mmKIZsNQiACiqsUqjvoQdkkjm6hi8t3gW7U3cBBezBuYo0cPeYFd4g2qBdsOasPmBJLY5FPKktfOka1nsBGL5dWxB-Qd5oMsYMXNSLayRfZ3yx-Iev89s3JlKgAxkL6YMV4JY-truM5d4M6aC6rpCFcNOv75ZgD]] {0x109e26c0} 0x1d8020 25 [] false 104.197.247.211 map[] map[] <nil> map[]   <nil> 0x10cdc000 <nil> <nil>}) %!s(*tls.ConnectionState=&{771 true false 49196 h2 true  [0x109b0000] [] [] [] [9 107 96 101 16 231 158 140 99 249 209 169]})}
2018-08-28 22:50:36 +0000 UTC info: State transition: update-store [Download] -> update-install [ArtifactInstall]
2018-08-28 22:50:36 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:50:36 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:50:36 +0000 UTC debug: status reported, response &{204 No Content %!s(int=204) HTTP/2.0 %!s(int=2) %!s(int=0) map[X-Men-Requestid:[6f30fc93-049f-4744-8d6c-d07b764ad9e3] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Xss-Protection:[1; mode=block] Pragma:[no-cache] Vary:[Accept-Encoding] X-Deployments-Version:[unknown] Content-Encoding:[gzip] X-Frame-Options:[DENY] X-Content-Type-Options:[nosniff] Cache-Control:[no-cache, no-store] Server:[openresty/1.13.6.2] Date:[Tue, 28 Aug 2018 22:50:36 GMT]] %!s(*http.cancelTimerBody=&{0x1d687c {0x10970b20} 0x1ddfc4}) %!s(int64=0) [] %!s(bool=false) %!s(bool=false) map[] %!s(*http.Request=&{PUT 0x1096f500 HTTP/1.1 1 1 map[Content-Type:[application/json] Authorization:[Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE1MzYwMzI5MjgsImp0aSI6Ijk2ODk0MTMzLWQ0MDktNDU3Ny04MTBhLTlkMDA0ZjNlNjUxYiIsImlzcyI6Ik1lbmRlciIsInN1YiI6IjViODRjNDdiOGRhNTk2MDAwMWUxMzMxYSIsIm1lbmRlci5kZXZpY2UiOnRydWV9.ZH7RefvYo7cNJtDPb5g5qNG_06vgE5pTCd7FdChC0vFle0WgsNJwIjO_jYEVnEyk7uhcBZyf7eHAqucX3mm-xnnfBMrGO1DEwdg_13e_jwl-6zmBcgG-PueJ2wFLw8YSpdCeIg3FdvkuObJww5qRq9LOAZcajHrzbZMzNttOzi3jC-t05KSNjt-2Gu5H6Slz6PNjccyEv_OHuizbtpCMGbXp3or8aLUJ0qtR83_FKPouW-WN_jraKad2p9z7tyqGWnshgSRAKxmT1450WS5JOIDTi6KUIeDwAUSStvsSX0Gn4Tq8exi9ZXSnqP0Y5nQpwso3a_35kSybOJbf8V8tZ-e22Lx03JCD2mmKIZsNQiACiqsUqjvoQdkkjm6hi8t3gW7U3cBBezBuYo0cPeYFd4g2qBdsOasPmBJLY5FPKktfOka1nsBGL5dWxB-Qd5oMsYMXNSLayRfZ3yx-Iev89s3JlKgAxkL6YMV4JY-truM5d4M6aC6rpCFcNOv75ZgD]] {0x109e2c00} 0x1d8020 24 [] false 104.197.247.211 map[] map[] <nil> map[]   <nil> 0x10cdcbc0 <nil> <nil>}) %!s(*tls.ConnectionState=&{771 true false 49196 h2 true  [0x109b0000] [] [] [] [9 107 96 101 16 231 158 140 99 249 209 169]})}
2018-08-28 22:50:36 +0000 UTC debug: Inactive partition: /dev/mmcblk0p3
2018-08-28 22:50:36 +0000 UTC debug: Marking inactive partition (/dev/mmcblk0p3) as the new boot candidate.
2018-08-28 22:50:36 +0000 UTC info: Enabling partition with new image installed to be a boot candidate: 3
2018-08-28 22:50:36 +0000 UTC debug: Marking inactive partition as a boot candidate successful.
2018-08-28 22:50:36 +0000 UTC info: State transition: update-install [ArtifactInstall] -> reboot [ArtifactReboot_Enter]
2018-08-28 22:50:36 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:50:36 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:50:36 +0000 UTC debug: handling reboot state
2018-08-28 22:50:37 +0000 UTC debug: status reported, response &{204 No Content %!s(int=204) HTTP/2.0 %!s(int=2) %!s(int=0) map[X-Frame-Options:[DENY] Pragma:[no-cache] Server:[openresty/1.13.6.2] Vary:[Accept-Encoding] X-Deployments-Version:[unknown] X-Men-Requestid:[3351d22f-6bd8-423f-9f4e-d4bf9a334f51] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Date:[Tue, 28 Aug 2018 22:50:37 GMT] Content-Encoding:[gzip] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Content-Type-Options:[nosniff]] %!s(*http.cancelTimerBody=&{0x1d687c {0x10970b20} 0x1ddfc4}) %!s(int64=0) [] %!s(bool=false) %!s(bool=false) map[] %!s(*http.Request=&{PUT 0x1096f980 HTTP/1.1 1 1 map[Content-Type:[application/json] Authorization:[Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE1MzYwMzI5MjgsImp0aSI6Ijk2ODk0MTMzLWQ0MDktNDU3Ny04MTBhLTlkMDA0ZjNlNjUxYiIsImlzcyI6Ik1lbmRlciIsInN1YiI6IjViODRjNDdiOGRhNTk2MDAwMWUxMzMxYSIsIm1lbmRlci5kZXZpY2UiOnRydWV9.ZH7RefvYo7cNJtDPb5g5qNG_06vgE5pTCd7FdChC0vFle0WgsNJwIjO_jYEVnEyk7uhcBZyf7eHAqucX3mm-xnnfBMrGO1DEwdg_13e_jwl-6zmBcgG-PueJ2wFLw8YSpdCeIg3FdvkuObJww5qRq9LOAZcajHrzbZMzNttOzi3jC-t05KSNjt-2Gu5H6Slz6PNjccyEv_OHuizbtpCMGbXp3or8aLUJ0qtR83_FKPouW-WN_jraKad2p9z7tyqGWnshgSRAKxmT1450WS5JOIDTi6KUIeDwAUSStvsSX0Gn4Tq8exi9ZXSnqP0Y5nQpwso3a_35kSybOJbf8V8tZ-e22Lx03JCD2mmKIZsNQiACiqsUqjvoQdkkjm6hi8t3gW7U3cBBezBuYo0cPeYFd4g2qBdsOasPmBJLY5FPKktfOka1nsBGL5dWxB-Qd5oMsYMXNSLayRfZ3yx-Iev89s3JlKgAxkL6YMV4JY-truM5d4M6aC6rpCFcNOv75ZgD]] {0x109e3020} 0x1d8020 23 [] false 104.197.247.211 map[] map[] <nil> map[]   <nil> 0x10cdcd40 <nil> <nil>}) %!s(*tls.ConnectionState=&{771 true false 49196 h2 true  [0x109b0000] [] [] [] [9 107 96 101 16 231 158 140 99 249 209 169]})}
2018-08-28 22:50:37 +0000 UTC info: rebooting device
1970-01-01 00:00:11 +0000 UTC debug: handling state after reboot
1970-01-01 00:00:11 +0000 UTC info: State transition: after-reboot [ArtifactReboot_Leave] -> update-verify [ArtifactReboot_Leave]
1970-01-01 00:00:11 +0000 UTC debug: handle update verify state
1970-01-01 00:00:11 +0000 UTC debug: Have U-Boot variable: upgrade_available=1
1970-01-01 00:00:11 +0000 UTC debug: List of U-Boot variables:map[upgrade_available:1]
1970-01-01 00:00:11 +0000 UTC info: State transition: update-verify [ArtifactReboot_Leave] -> update-commit [ArtifactCommit]
1970-01-01 00:00:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
1970-01-01 00:00:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
1970-01-01 00:00:12 +0000 UTC debug: start executing script: ArtifactCommit_Enter_10_wait-for-timesync
1970-01-01 00:00:12 +0000 UTC error: failed to report status: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on [2001:4860:4860::8888]:53: dial udp [2001:4860:4860::8888]:53: connect: network is unreachable
1970-01-01 00:00:12 +0000 UTC error: statescript: can not send start status to server: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on [2001:4860:4860::8888]:53: dial udp [2001:4860:4860::8888]:53: connect: network is unreachable
2018-08-28 22:51:39 +0000 UTC error: stderr collected while running script /var/lib/mender/scripts/ArtifactCommit_Enter_10_wait-for-timesync [timedatectl: System time not yet synchronized; try #1
timedatectl: System time not yet synchronized; try #2
timedatectl: System time not yet synchronized; try #3
timedatectl: System time not yet synchronized; try #4
timedatectl: System time not yet synchronized; try #5
timedatectl: System time not yet synchronized; try #6
timedatectl: System time not yet synchronized; try #7
System time now properly synchronized; try #8
Exiting /var/lib/mender/scripts/ArtifactCommit_Enter_10_wait-for-timesync at Tue Aug 28 22:51:39 UTC 2018
]
2018-08-28 22:51:41 +0000 UTC error: failed to report status: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:51:41 +0000 UTC error: statescript: can not send finished status to server: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:51:41 +0000 UTC debug: handle update commit state
2018-08-28 22:51:41 +0000 UTC debug: Read data from device manifest file: artifact_name=release-2
2018-08-28 22:51:41 +0000 UTC debug: Found needed line: artifact_name=release-2
2018-08-28 22:51:41 +0000 UTC debug: Current manifest data: release-2
2018-08-28 22:51:41 +0000 UTC info: successfully running with new image release-2
2018-08-28 22:51:41 +0000 UTC debug: Have U-Boot variable: upgrade_available=1
2018-08-28 22:51:41 +0000 UTC debug: List of U-Boot variables:map[upgrade_available:1]
2018-08-28 22:51:41 +0000 UTC info: Commiting update
2018-08-28 22:51:41 +0000 UTC info: Storing commit state data
2018-08-28 22:51:41 +0000 UTC info: State transition: update-commit [ArtifactCommit] -> update-status-report [none]
2018-08-28 22:51:41 +0000 UTC debug: handle update status report state
2018-08-28 22:51:41 +0000 UTC error: failed to report status: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:51:41 +0000 UTC error: error reporting update status: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:51:41 +0000 UTC error: failed to send status to server: transient error: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:51:41 +0000 UTC info: State transition: update-status-report [ArtifactCommit] -> update-retry-report [none]
2018-08-28 22:52:11 +0000 UTC debug: wait complete
2018-08-28 22:52:11 +0000 UTC info: State transition: update-retry-report [ArtifactCommit] -> update-status-report [ArtifactCommit]
2018-08-28 22:52:11 +0000 UTC debug: handle update status report state
2018-08-28 22:52:11 +0000 UTC error: failed to report status: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:52:11 +0000 UTC error: error reporting update status: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:52:11 +0000 UTC error: failed to send status to server: transient error: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:52:11 +0000 UTC info: State transition: update-status-report [ArtifactCommit] -> update-retry-report [none]
2018-08-28 22:52:42 +0000 UTC debug: wait complete
2018-08-28 22:52:42 +0000 UTC info: State transition: update-retry-report [ArtifactCommit] -> update-status-report [ArtifactCommit]
2018-08-28 22:52:42 +0000 UTC debug: handle update status report state
2018-08-28 22:52:42 +0000 UTC error: failed to report status: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:52:42 +0000 UTC error: error reporting update status: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:52:42 +0000 UTC error: failed to send status to server: transient error: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:52:42 +0000 UTC info: State transition: update-status-report [ArtifactCommit] -> update-retry-report [none]
2018-08-28 22:53:12 +0000 UTC debug: wait complete
2018-08-28 22:53:12 +0000 UTC info: State transition: update-retry-report [ArtifactCommit] -> update-status-report [ArtifactCommit]
2018-08-28 22:53:12 +0000 UTC debug: handle update status report state
2018-08-28 22:53:12 +0000 UTC error: failed to report status: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:53:12 +0000 UTC error: error reporting update status: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:53:12 +0000 UTC error: failed to send status to server: transient error: reporting status failed: Put https://mender.gcpotademo.com/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp: lookup mender.gcpotademo.com on 192.168.86.1:53: no such host
2018-08-28 22:53:12 +0000 UTC info: State transition: update-status-report [ArtifactCommit] -> update-retry-report [none]
2018-08-28 22:53:12 +0000 UTC info: State transition: update-retry-report [ArtifactCommit] -> status-report-error [ArtifactFailure]
2018-08-28 22:53:12 +0000 UTC debug: transitioning to error state
2018-08-28 22:53:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:53:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:53:12 +0000 UTC error: handling report error state with status: success
2018-08-28 22:53:12 +0000 UTC info: State transition: status-report-error [ArtifactFailure] -> rollback [ArtifactRollback]
2018-08-28 22:53:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:53:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:53:12 +0000 UTC info: performing rollback
2018-08-28 22:53:12 +0000 UTC debug: Have U-Boot variable: mender_boot_part=3
2018-08-28 22:53:12 +0000 UTC debug: List of U-Boot variables:map[mender_boot_part:3]
2018-08-28 22:53:12 +0000 UTC debug: Setting active partition from mount candidate: /dev/mmcblk0p3
2018-08-28 22:53:12 +0000 UTC debug: Detected inactive partition /dev/mmcblk0p2, based on active partition /dev/mmcblk0p3
2018-08-28 22:53:12 +0000 UTC debug: Marking inactive partition (/dev/mmcblk0p2) as the new boot candidate.
2018-08-28 22:53:12 +0000 UTC info: setting partition for rollback: 2
2018-08-28 22:53:12 +0000 UTC debug: Marking inactive partition as a boot candidate successful.
2018-08-28 22:53:12 +0000 UTC debug: will try to rollback reboot the device
2018-08-28 22:53:12 +0000 UTC info: State transition: rollback [ArtifactRollback] -> rollback-reboot [ArtifactRollbackReboot_Enter]
2018-08-28 22:53:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:53:12 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:53:12 +0000 UTC info: rebooting device after rollback
2018-08-28 22:50:42 +0000 UTC debug: handling state after rollback reboot
2018-08-28 22:50:42 +0000 UTC info: State transition: after-rollback-reboot [ArtifactRollbackReboot_Leave] -> update-error [ArtifactFailure]
2018-08-28 22:50:42 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:50:42 +0000 UTC debug: statescript: timeout for executing scripts is not defined; using default of 60 seconds
2018-08-28 22:50:42 +0000 UTC debug: handle update error state
2018-08-28 22:50:42 +0000 UTC info: State transition: update-error [ArtifactFailure] -> update-status-report [none]
2018-08-28 22:50:42 +0000 UTC debug: handle update status report state
2018-08-28 22:50:42 +0000 UTC error: failed to report status: Put https://104.197.247.211/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp 104.197.247.211:443: connect: network is unreachable
2018-08-28 22:50:42 +0000 UTC error: error reporting update status: reporting status failed: Put https://104.197.247.211/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp 104.197.247.211:443: connect: network is unreachable
2018-08-28 22:50:42 +0000 UTC error: failed to send status to server: transient error: reporting status failed: Put https://104.197.247.211/api/devices/v1/deployments/device/deployments/547f8452-c659-4404-9119-41c099a8189b/status: dial tcp 104.197.247.211:443: connect: network is unreachable
2018-08-28 22:50:42 +0000 UTC info: State transition: update-status-report [ArtifactFailure] -> update-retry-report [none]
2018-08-28 22:51:12 +0000 UTC debug: wait complete
2018-08-28 22:51:13 +0000 UTC info: State transition: update-retry-report [ArtifactFailure] -> update-status-report [ArtifactFailure]
2018-08-28 22:51:13 +0000 UTC debug: handle update status report state
2018-08-28 22:51:13 +0000 UTC debug: status reported, response &{204 No Content %!s(int=204) HTTP/2.0 %!s(int=2) %!s(int=0) map[Pragma:[no-cache] Content-Encoding:[gzip] Vary:[Accept-Encoding] Strict-Transport-Security:[max-age=63072000; includeSubdomains; preload] X-Content-Type-Options:[nosniff] X-Frame-Options:[DENY] X-Xss-Protection:[1; mode=block] Cache-Control:[no-cache, no-store] Server:[openresty/1.13.6.2] Date:[Tue, 28 Aug 2018 22:54:09 GMT] X-Deployments-Version:[unknown] X-Men-Requestid:[d3221bf6-b222-4ce9-b36d-9dd933422183]] %!s(*http.cancelTimerBody=&{0x1d687c {0x1116eb20} 0x1ddfc4}) %!s(int64=0) [] %!s(bool=false) %!s(bool=false) map[] %!s(*http.Request=&{PUT 0x1116b500 HTTP/1.1 1 1 map[Content-Type:[application/json] Authorization:[Bearer eyJhbGciOiJSUzI1NiIsInR5cCI6IkpXVCJ9.eyJleHAiOjE1MzYwMzI5MjgsImp0aSI6Ijk2ODk0MTMzLWQ0MDktNDU3Ny04MTBhLTlkMDA0ZjNlNjUxYiIsImlzcyI6Ik1lbmRlciIsInN1YiI6IjViODRjNDdiOGRhNTk2MDAwMWUxMzMxYSIsIm1lbmRlci5kZXZpY2UiOnRydWV9.ZH7RefvYo7cNJtDPb5g5qNG_06vgE5pTCd7FdChC0vFle0WgsNJwIjO_jYEVnEyk7uhcBZyf7eHAqucX3mm-xnnfBMrGO1DEwdg_13e_jwl-6zmBcgG-PueJ2wFLw8YSpdCeIg3FdvkuObJww5qRq9LOAZcajHrzbZMzNttOzi3jC-t05KSNjt-2Gu5H6Slz6PNjccyEv_OHuizbtpCMGbXp3or8aLUJ0qtR83_FKPouW-WN_jraKad2p9z7tyqGWnshgSRAKxmT1450WS5JOIDTi6KUIeDwAUSStvsSX0Gn4Tq8exi9ZXSnqP0Y5nQpwso3a_35kSybOJbf8V8tZ-e22Lx03JCD2mmKIZsNQiACiqsUqjvoQdkkjm6hi8t3gW7U3cBBezBuYo0cPeYFd4g2qBdsOasPmBJLY5FPKktfOka1nsBGL5dWxB-Qd5oMsYMXNSLayRfZ3yx-Iev89s3JlKgAxkL6YMV4JY-truM5d4M6aC6rpCFcNOv75ZgD]] {0x112480c0} 0x1d8020 21 [] false 104.197.247.211 map[] map[] <nil> map[]   <nil> 0x11168ac0 <nil> <nil>}) %!s(*tls.ConnectionState=&{771 true false 49196 h2 true  [0x11264000] [] [] [] [217 31 50 52 181 113 163 101 40 96 248 35]})}
2018-08-28 22:51:13 +0000 UTC debug: attempting to upload deployment logs for failed update