Comment 4 for bug 1435109

Revision history for this message
Ricardo Salveti (rsalveti) wrote :

With the latest available at lp:ubuntu-push/automatic:
2015/03/23 17:26:40.203525 poller.go:178: DEBUG got wakelock cookie of d3817bb1-b7ec-4204-846b-d1551d9ab487
2015/03/23 17:26:50.709443 networkmanager.go:145: DEBUG got primary connection: /org/freedesktop/NetworkManager/ActiveConnection/1
2015/03/23 17:26:50.709972 connectivity.go:164: DEBUG connectivity: PrimaryConnection changed. lastSent: true, sending 'disconnected'.
2015/03/23 17:26:50.710324 session.go:283: DEBUG session.setState: Running -> Disconnected
2015/03/23 17:26:50.710548 session.go:586: DEBUG session aborting with error on read.
2015/03/23 17:26:50.710971 session.go:283: DEBUG session.setState: Disconnected -> Error
2015/03/23 17:26:50.714776 session.go:766: ERROR session error'ed out with read tcp 91.189.88.31:443: use of closed network connection
2015/03/23 17:28:58.394450 connectivity.go:197: DEBUG connectivity: timer signal, state: ConnectedGlobal, checking...
2015/03/23 17:29:01.228832 webchecker.go:87: INFO connectivity check passed.
2015/03/23 17:29:01.229208 connectivity.go:157: DEBUG connectivity: connection check says: true
2015/03/23 17:29:01.229336 connectivity.go:157: DEBUG connectivity: connection check ok, lastSent: false, sending 'connected'.
2015/03/23 17:29:01.229526 session.go:433: DEBUG session autoredialier launching Redial goroutine
2015/03/23 17:29:01.229785 session.go:283: DEBUG session.setState: Error -> Disconnected
2015/03/23 17:29:01.229894 session.go:347: DEBUG adding authorization
2015/03/23 17:29:01.230263 client.go:257: DEBUG getting authorization for https://push.ubuntu.com/
2015/03/23 17:29:01.993163 session.go:403: DEBUG trying to connect to: 91.189.88.31:443
2015/03/23 17:29:02.302225 session.go:283: DEBUG session.setState: Disconnected -> Connected
2015/03/23 17:29:03.562364 session.go:657: DEBUG connected 91.189.88.31:443.
2015/03/23 17:29:03.562637 session.go:283: DEBUG session.setState: Connected -> Started
2015/03/23 17:29:03.562870 session.go:283: DEBUG session.setState: Started -> Running
2015/03/23 17:29:03.563195 session.go:743: DEBUG connected after 1 attempts.
2015/03/23 17:29:04.395063 poller.go:178: DEBUG poking polld.
2015/03/23 17:29:04.399025 poller.go:178: DEBUG waiting for polld to signal Done.
2015/03/23 17:29:05.837456 endpoint.go:278: DEBUG WatchMethod: ListPersistent(/com/ubuntu/Postal/com_2eubuntu_2edeveloper_2ewebapps_2ewebapp_2dgmail, []interface {}{"com.ubuntu.developer.webapps.webapp-gmail_webapp-gmail"}, []interface {}{(*service.DBusService)(0x956c2180)}) success: []string{"[]"}
2015/03/23 17:29:06.998565 endpoint.go:278: DEBUG WatchMethod: ListPersistent(/com/ubuntu/Postal/com_2eubuntu_2edeveloper_2ewebapps_2ewebapp_2dtwitter, []interface {}{"com.ubuntu.developer.webapps.webapp-twitter_webapp-twitter"}, []interface {}{(*service.DBusService)(0x956c2180)}) success: []string{"[]"}
2015/03/23 17:29:06.999717 poller.go:178: DEBUG polld Done.
2015/03/23 17:29:12.009293 poller.go:154: DEBUG requested wakeup at 2015-03-23 17:34:12 -0300 BRT
2015/03/23 17:29:12.037118 poller.go:178: DEBUG cleared wakelock cookie d3817bb1-b7ec-4204-846b-d1551d9ab487.

Almost 3 minutes with the log, in this case it seems because the network connection was closed.