* [RAUC] Rauc and Hawkbit server
@ 2017-12-05 16:46 Laurent GUILLIER
2017-12-06 15:27 ` Enrico Joerns
0 siblings, 1 reply; 3+ messages in thread
From: Laurent GUILLIER @ 2017-12-05 16:46 UTC (permalink / raw)
To: rauc
Hi,
first of all thanks for Rauc which is a very nice piece of software!
I am now trying to use rauc on a imx6 target, along with hawkbit as the
update server. Everything runs fine, except that after the reboot
following the successful slot update, the rauc-hawkbit client detects
the update again, and starts the process again and again ... the slots
are alternated as it would expected if each update cycle was required
(i.e. different bundles).
Looks like the client doesn't tell hawkbit the update process was
succesfully performed, from downloading to reboot on the brand new slot.
Any clue to solve that little issue would be appreciated!
Regards
Laurent
_______________________________________________
RAUC mailing list
^ permalink raw reply [flat|nested] 3+ messages in thread
* Re: [RAUC] Rauc and Hawkbit server
2017-12-05 16:46 [RAUC] Rauc and Hawkbit server Laurent GUILLIER
@ 2017-12-06 15:27 ` Enrico Joerns
0 siblings, 0 replies; 3+ messages in thread
From: Enrico Joerns @ 2017-12-06 15:27 UTC (permalink / raw)
To: Laurent GUILLIER; +Cc: rauc
Hi,
On 12/05/2017 05:46 PM, Laurent GUILLIER wrote:
> Hi,
>
> first of all thanks for Rauc which is a very nice piece of software!
>
> I am now trying to use rauc on a imx6 target, along with hawkbit as the
> update server. Everything runs fine, except that after the reboot
> following the successful slot update, the rauc-hawkbit client detects
> the update again, and starts the process again and again ... the slots
> are alternated as it would expected if each update cycle was required
> (i.e. different bundles).
>
> Looks like the client doesn't tell hawkbit the update process was
> succesfully performed, from downloading to reboot on the brand new slot.
> Any clue to solve that little issue would be appreciated!
do you have any log from the rauc-hawkbit python client running during
the update? Not that you can also start it in debug mode to increase
verbosity as listed here [1]. A log would help finding out what is going
on and what not.
And, do you see feedback in the targets Action history view in the
hawkBit Web UI? As in the Demo video [2] shown at 0:25. The update must
have a green mark to be considered successful by hawkBit and to not
trigger an update again.
Best regards
Enrico
[1] https://github.com/rauc/rauc-hawkbit#debugging
[2] https://www.youtube.com/watch?v=SLX0Fd_y6Cc
--
Pengutronix e.K. | Enrico Jörns |
Industrial Linux Solutions | http://www.pengutronix.de/ |
Peiner Str. 6-8, 31137 Hildesheim, Germany | Phone: +49-5121-206917-5080 |
Amtsgericht Hildesheim, HRA 2686 | Fax: +49-5121-206917-5555 |
_______________________________________________
RAUC mailing list
^ permalink raw reply [flat|nested] 3+ messages in thread
* Re: [RAUC] Rauc and Hawkbit server
@ 2017-12-08 8:32 Laurent GUILLIER
0 siblings, 0 replies; 3+ messages in thread
From: Laurent GUILLIER @ 2017-12-08 8:32 UTC (permalink / raw)
To: rauc
Hi Enrico,
thanks for your reply.
Actually the green mark does not appear on the hawkbit web UI update
status, and thus the process is not considered as successful by hawkbit
server and is retried on next boot.
Here are some logs from the rauc-client, after the first boot :
root@imx6dl-riotboard:~# journalctl -f -u rauc*
-- Logs begin at Tue 2017-11-14 11:03:51 UTC. --
Dec 07 15:43:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:43:22
INFO Will sleep for 00:02:00
Dec 07 15:45:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:45:22
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0
Dec 07 15:45:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:45:22
DEBUG {'config': {'polling': {'sleep': '00:02:00'}}}
Dec 07 15:45:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:45:22
INFO Will sleep for 00:02:00
Dec 07 15:47:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:47:22
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0
Dec 07 15:47:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:47:22
DEBUG {'config': {'polling': {'sleep': '00:02:00'}}}
Dec 07 15:47:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:47:22
INFO Will sleep for 00:02:00
Dec 07 15:49:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:49:22
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0
Dec 07 15:49:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:49:22
DEBUG {'config': {'polling': {'sleep': '00:02:00'}}}
Dec 07 15:49:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:49:22
INFO Will sleep for 00:02:00
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
DEBUG {'config': {'polling': {'sleep': '00:02:00'}}, '_links':
{'deploymentBase': {'href':
'http://10.12.90.103:8080/DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/D
EFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1?c=411629670'}}}
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
INFO Deployment found for this target
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
DEBUG {'id': '1', 'deployment': {'download': 'forced', 'chunks':
[{'name': 'Hello', 'part': 'os', 'artifacts': [{'hashes': {'md5':
'd53f3d6b420f142f7746bd48789445e
9', 'sha1': '4282dc91952406e184079567ef8dbe7e36f7c4e3'}, 'filename':
'update-bundle-imx6dl-riotboard-20171207153430.raucb', 'size': 34883096,
'_links': {'md5sum-http': {'href':
'http://10.12.90.103:8080/DEFAULT/controller/v1/riot_0a:14:2e
:95:8e:e0/softwaremodules/1/artifacts/update-bundle-imx6dl-riotboard-20171207153430.raucb.MD5SUM'},
'download-http': {'href':
'http://10.12.90.103:8080/DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/softwaremodules/1/artifacts/update-bundle
-imx6dl-riotboard-20171207153430.raucb'}}}], 'version': '1.1'}],
'update': 'forced'}}
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
INFO Starting bundle download
Dec 07 15:51:22 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:22
DEBUG GET binary
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/softwaremodules/1/artifacts/update-bundle-imx6dl-riotboard-20171207153430.r
aucb
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
INFO Download successful
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
INFO Starting installation
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: input bundle: /tmp/bundle.raucb
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: Active slot bootname: B
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
INFO Will sleep for 00:02:00
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: started
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
INFO Update progress: 0% Installing
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: rauc-Message: Mounting
bundle '/tmp/bundle.raucb' to '/mnt/rauc/bundle'
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: Checking and mounting bundle...
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: rauc-Message: Reading
bundle: /tmp/bundle.raucb
Dec 07 15:51:57 imx6dl-riotboard rauc[282]: rauc-Message: Verifying
bundle...
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
INFO Update progress: 0% Determining slot states
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:57 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
INFO Update progress: 20% Determining slot states done.
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:57
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
INFO Update progress: 20% Checking bundle
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
INFO Update progress: 20% Verifying signature
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
INFO Update progress: 40% Verifying signature done.
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
INFO Update progress: 40% Checking bundle done.
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
INFO Update progress: 50% Verifying manifest checksums
Dec 07 15:51:58 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:51:58
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
[ 2529.707770] EXT4-fs (mmcblk1p2): recovery complete
[ 2529.720763] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data
mode. Opts: (null)
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
INFO Update progress: 60% Verifying manifest checksums done.
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: Adding to target group:
rootfs -> rootfs.0
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: Target Group:
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rootfs -> rootfs.0
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: Using default handler
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rauc-Message: Marking target
slot as non-bootable...
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rauc-Message: Checking image
type for slot type: ext4
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rauc-Message: Image detected
as type: *.ext4
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rauc-Message: mounting slot
/dev/mmcblk1p2
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: Updating slots...
Dec 07 15:52:10 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: Checking slot rootfs.0
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
INFO Update progress: 60% Verifying manifest done.
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
INFO Update progress: 60% Determining target install group
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
INFO Update progress: 80% Determining target install group done.
Dec 07 15:52:10 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:10
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:11 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:11
INFO Update progress: 80% Checking slot rootfs.0
Dec 07 15:52:11 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:11
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:11 imx6dl-riotboard rauc[282]: rauc-Message: Failed to load
slot status file: No such file or directory
Dec 07 15:52:11 imx6dl-riotboard rauc[282]: rauc-Message: Updating
/dev/mmcblk1p2 with
/mnt/rauc/bundle/core-image-minimal-imx6dl-riotboard.ext4
Dec 07 15:52:11 imx6dl-riotboard rauc[282]: rauc-Message: opening slot
device /dev/mmcblk1p2
Dec 07 15:52:11 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: Updating slot rootfs.0
Dec 07 15:52:11 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:11
INFO Update progress: 90% Checking slot rootfs.0 done.
Dec 07 15:52:11 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:11
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:11 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:11
INFO Update progress: 90% Copying image
Dec 07 15:52:11 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:11
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
[ 2567.647271] EXT4-fs (mmcblk1p2): mounted filesystem with ordered data
mode. Opts: (null)
Dec 07 15:52:48 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:48
INFO Update progress: 100% Copying image done.
Dec 07 15:52:48 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:48
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:48 imx6dl-riotboard rauc[282]: rauc-Message: Updating slot
file /mnt/rauc/rootfs.0/slot.raucs
Dec 07 15:52:48 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: Updating slot rootfs.0 status
Dec 07 15:52:49 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: Updating slot rootfs.0 done
Dec 07 15:52:49 imx6dl-riotboard rauc[282]: rauc-Message: Marking slots
as bootable...
Dec 07 15:52:49 imx6dl-riotboard rauc[282]: Starting post install
handler: /usr/lib/rauc/post-install.sh
Dec 07 15:52:49 imx6dl-riotboard rauc[282]: rauc-Message: installing
/tmp/bundle.raucb: All slots updated
Dec 07 15:52:49 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:49
INFO Update progress: 100% Updating slots done.
Dec 07 15:52:49 imx6dl-riotboard rauc-hawkbit[255]: 2017-12-07 15:52:49
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 15:52:49 imx6dl-riotboard rauc[282]: # Reboot scheduled in 60sec
Here we can see the slot is updated and reboot is scheduled by
post-install process.
Messages on hawkbit server are OK, from "Target retrieved update action
and should start now the download." to "Updating slots done."
Now, here is what happens on next reboot :
Dec 07 16:01:36 imx6dl-riotboard systemd[1]: Started RAUC Good-marking
Service.
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: Compatible: RioTHello
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: booted from: A
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: slot states:
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: rootfs.0: class=rootfs,
device=/dev/mmcblk1p2, type=ext4, bootname=A
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: state=booted,
description=, parent=(none), mountpoint=(none)
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: rootfs.1: class=rootfs,
device=/dev/mmcblk1p3, type=ext4, bootname=B
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: state=inactive,
description=, parent=(none), mountpoint=(none)
Dec 07 16:01:37 imx6dl-riotboard rauc[257]: marking slot rootfs.0 as good
Dec 07 16:01:38 imx6dl-riotboard systemd[1]: Starting rauc-hawkbit...
Dec 07 16:01:38 imx6dl-riotboard systemd[1]: Started rauc-hawkbit.
Dec 07 16:01:38 imx6dl-riotboard rauc-hawkbit[273]: Starting
/usr/bin/rauc-hawkbit-client ...
Dec 07 16:01:45 imx6dl-riotboard systemd[1]: Starting Rauc Update Service...
Dec 07 16:01:46 imx6dl-riotboard rauc[303]: (rauc:303):
GLib-GObject-CRITICAL **: g_object_set: assertion 'G_IS_OBJECT (object)'
failed
Dec 07 16:01:46 imx6dl-riotboard systemd[1]: Started Rauc Update Service.
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
DEBUG {'config': {'polling': {'sleep': '00:02:00'}}, '_links':
{'deploymentBase': {'href':
'http://10.12.90.103:8080/DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1?c=411659461'}}}
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
INFO Deployment found for this target
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
DEBUG GET
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
DEBUG {'id': '1', 'deployment': {'download': 'forced', 'update':
'forced', 'chunks': [{'artifacts': [{'size': 34883096, '_links':
{'md5sum-http': {'href':
'http://10.12.90.103:8080/DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/softwaremodules/1/artifacts/update-bundle-imx6dl-riotboard-20171207153430.raucb.MD5SUM'},
'download-http': {'href':
'http://10.12.90.103:8080/DEFAULT/controller/v1/riot_0a:14:
2e:95:8e:e0/softwaremodules/1/artifacts/update-bundle-imx6dl-riotboard-20171207153430.raucb'}},
'hashes': {'sha1': '4282dc91952406e184079567ef8dbe7e36f7c4e3', 'md5':
'd53f3d6b420f142f7746bd48789445e9'}, 'filename':
'update-bundle-imx6dl-riotboard-20171207153430.raucb'}], 'version':
'1.1', 'part': 'os', 'name': 'Hello'}]}}
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
INFO Starting bundle download
Dec 07 16:01:46 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:01:46
DEBUG GET binary
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/softwaremodules/1/artifacts/update-bundle-imx6dl-riotboard-20171207153430.raucb
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Download successful
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Starting installation
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: input bundle: /tmp/bundle.raucb
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: Active slot bootname: A
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: started
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Will sleep for 00:02:00
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: rauc-Message: Mounting
bundle '/tmp/bundle.raucb' to '/mnt/rauc/bundle'
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: Checking and mounting bundle...
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: rauc-Message: Reading
bundle: /tmp/bundle.raucb
Dec 07 16:02:21 imx6dl-riotboard rauc[303]: rauc-Message: Verifying
bundle...
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Update progress: 0% Installing
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Update progress: 0% Determining slot states
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Update progress: 20% Determining slot states done.
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Update progress: 20% Checking bundle
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
INFO Update progress: 20% Verifying signature
Dec 07 16:02:21 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:21
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
INFO Update progress: 40% Verifying signature done.
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
INFO Update progress: 40% Checking bundle done.
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
INFO Update progress: 40% Loading manifest file
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
INFO Update progress: 50% Loading manifest file done.
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
INFO Update progress: 50% Verifying manifest checksums
Dec 07 16:02:22 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:22
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 60% Verifying manifest checksums done.
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: Adding to target group:
rootfs -> rootfs.1
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: Target Group:
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rootfs -> rootfs.1
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: Using default handler
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: Marking target
slot as non-bootable...
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: Checking image
type for slot type: ext4
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: Image detected
as type: *.ext4
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: mounting slot
/dev/mmcblk1p3
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: Updating slots...
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: Checking slot rootfs.1
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 60% Verifying manifest done.
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 60% Determining target install group
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 80% Determining target install group done.
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: Failed to load
slot status file: No such file or directory
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 80% Checking slot rootfs.1
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: Updating
/dev/mmcblk1p3 with
/mnt/rauc/bundle/core-image-minimal-imx6dl-riotboard.ext4
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: opening slot
device /dev/mmcblk1p3
Dec 07 16:02:34 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: Updating slot rootfs.1
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 90% Checking slot rootfs.1 done.
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
INFO Update progress: 90% Copying image
Dec 07 16:02:34 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:02:34
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:03:12 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:03:12
INFO Update progress: 100% Copying image done.
Dec 07 16:03:12 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:03:12
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: rauc-Message: Updating slot
file /mnt/rauc/rootfs.1/slot.raucs
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: Updating slot rootfs.1 status
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: rauc-Message: Marking slots
as bootable...
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: Updating slot rootfs.1 done
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: Starting post install
handler: /usr/lib/rauc/post-install.sh
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: rauc-Message: installing
/tmp/bundle.raucb: All slots updated
Dec 07 16:03:12 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:03:12
INFO Update progress: 100% Updating slots done.
Dec 07 16:03:12 imx6dl-riotboard rauc-hawkbit[273]: 2017-12-07 16:03:12
DEBUG POST
http://10.12.90.103:8080//DEFAULT/controller/v1/riot_0a:14:2e:95:8e:e0/deploymentBase/1/feedback
Dec 07 16:03:12 imx6dl-riotboard rauc[303]: # Reboot scheduled in 60sec
[ OK ] Stopped target Multi-User System.
As we can see, the update is performed again ...
Messages on hawkbit server are exactly the same as for previous update.
It looks like the server miss a message telling it that the update has
been permformed and that everything is OK. Leaving the system alive will
result in a never ending update / reboot process.
Thanks for any though that would help me finish the update.
Regards
Laurent
_______________________________________________
RAUC mailing list
^ permalink raw reply [flat|nested] 3+ messages in thread
end of thread, other threads:[~2017-12-08 8:32 UTC | newest]
Thread overview: 3+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2017-12-05 16:46 [RAUC] Rauc and Hawkbit server Laurent GUILLIER
2017-12-06 15:27 ` Enrico Joerns
2017-12-08 8:32 Laurent GUILLIER
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox