balena-os / balena-supervisor

Balena Supervisor: balena's agent on devices.
https://balena.io
Other
149 stars 63 forks source link

Improve logging when performing a release #1443

Open 20k-ultra opened 4 years ago

20k-ultra commented 4 years ago

In the following log we can see an application release being rolled out. It is unclear why the Image Removed log took so long. We could improve this by logging when actions are starting and when they are waiting if they have some kind of poll or retry mechanism.

note: I've modified some of the values to remove real data.

Aug 18 08:18:20 f3e50a9 resin-supervisor[4700]: [event]   Event: Service exit {"service":{"appId":2222222,"serviceId":266614,"serviceName":"my-service","releaseId":2222222}}
Aug 18 08:18:20 f3e50a9 resin-supervisor[4700]: [event]   Event: Service stop {"service":{"appId":2222222,"serviceId":266614,"serviceName":"my-service","releaseId":2222222}}
Aug 18 08:23:09 f3e50a9 resin-supervisor[4700]: [event]   Event: Image removed {"image":{"name":"registry2.balena-cloud.com/v2/...","appId":2222222,"serviceId":266614,"serviceName":"my-service","imageId":2574537,"releaseId":2222222,"dependent":0}}
Aug 18 08:23:10 f3e50a9 resin-supervisor[4700]: [event]   Event: Service install {"service":{"appId":2222222,"serviceId":266614,"serviceName":"my-service","releaseId":3333333}}
Aug 18 08:23:10 f3e50a9 resin-supervisor[4700]: [event]   Event: Service installed {"service":{"appId":2222222,"serviceId":266614,"serviceName":"my-service","releaseId":3333333}}
Aug 18 08:23:10 f3e50a9 resin-supervisor[4700]: [event]   Event: Service start {"service":{"appId":2222222,"serviceId":266614,"serviceName":"my-service","releaseId":3333333}}
Aug 18 08:23:12 f3e50a9 resin-supervisor[4700]: [event]   Event: Service started {"service":{"appId":2222222,"serviceId":266614,"serviceName":"my-service","releaseId":3333333}}
jellyfish-bot commented 4 years ago

[20k-ultra] This issue has attached support thread https://jel.ly.fish/850e3526-25a8-473f-ad72-47a3d3dc943e