Public bug reported:

snapd version: 2.16 on xenial (also saw with 2.15.2ubuntu1 before
upgrading)

While trying to manually install the jenkins snap, I concurrently
upgraded snapd with apt-get (although this could happen automatically if
I had auto-upgrades enabled). The apt upgrade finished but the jenkins
installed failed shortly after downloading the jenkins tarball (I've
lost the error message). After this, all snap commands failed with:

error: cannot list snaps: cannot communicate with server: Get
http://localhost/v2/snaps: dial unix /run/snapd.socket: connect:
connection refused


The snapd service was stopped. Attempts to restart failed with the following 
logs in journalctl:

Oct 03 07:07:24 blackened systemd[1]: Started Snappy daemon.
Oct 03 07:07:24 blackened /usr/lib/snapd/snapd[18474]: patch.go:66: Patching 
system state from level 3 to 4
Oct 03 07:07:24 blackened snapd[18474]: 2016/10/03 07:07:24.695954 patch.go:66: 
Patching system state from level 3 to 4
Oct 03 07:07:24 blackened /usr/lib/snapd/snapd[18474]: patch.go:73: Cannnot 
patch: cannot get snap state for "jenkins": <nil>
Oct 03 07:07:24 blackened snapd[18474]: 2016/10/03 07:07:24.705743 patch.go:73: 
Cannnot patch: cannot get snap state for "jenkins": <nil>
Oct 03 07:07:24 blackened snapd[18474]: error: cannot patch system state from 
level 3 to 4: cannot get snap state for "jenkins": <nil>
Oct 03 07:07:24 blackened systemd[1]: snapd.service: Main process exited, 
code=exited, status=1/FAILURE
Oct 03 07:07:24 blackened systemd[1]: snapd.service: Unit entered failed state.
Oct 03 07:07:24 blackened systemd[1]: snapd.service: Failed with result 
'exit-code'.


I discovered /var/lib/snapd/state.json had numerous changes and task entries 
for jenkins. After manually removing these with a json editor and updating the 
last-change-id and last-task-id pointers, snapd started again. I have the 
before and after files if they will help, ping me (fginther) in IRC.


Oct 03 07:54:00 blackened systemd[1]: Starting Socket activation for snappy 
daemon.
Oct 03 07:54:00 blackened systemd[1]: Listening on Socket activation for snappy 
daemon.
Oct 03 07:54:00 blackened systemd[1]: Started Snappy daemon.
Oct 03 07:54:00 blackened sudo[23948]: pam_unix(sudo:session): session closed 
for user root
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: patch.go:66: Patching 
system state from level 3 to 4
Oct 03 07:54:00 blackened snapd[23997]: 2016/10/03 07:54:00.743549 patch.go:66: 
Patching system state from level 3 to 4
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/system-info
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/login
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/logout
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/icons/{name}/icon
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/find
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/snaps
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/snaps/{name}
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/snaps/{name}/conf
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/interfaces
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/assertions
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/assertions/{assertType}
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/events
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/changes/{id}
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/changes
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/create-user
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/buy
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/buy/ready
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/buy/methods
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:219: DEBUG: 
adding /v2/snapctl
Oct 03 07:54:00 blackened /usr/lib/snapd/snapd[23997]: daemon.go:209: DEBUG: 
init done in 776.949µs

** Affects: snapd (Ubuntu)
     Importance: Undecided
         Status: New

-- 
You received this bug notification because you are a member of Ubuntu
Bugs, which is subscribed to Ubuntu.
https://bugs.launchpad.net/bugs/1629871

Title:
  upgrading snapd while concurrently installing a snap led to a busted
  state.json

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/snapd/+bug/1629871/+subscriptions

-- 
ubuntu-bugs mailing list
ubuntu-bugs@lists.ubuntu.com
https://lists.ubuntu.com/mailman/listinfo/ubuntu-bugs

Reply via email to