Unit test failure: watcherSuite.TestOfferStatusWatcher

Bug #1790076 reported by Joseph Phillips
6
This bug affects 1 person
Affects Status Importance Assigned to Milestone
Canonical Juju
Expired
Low
Unassigned

Bug Description

As seen here:
http://ci.jujucharms.com/job/github-check-merge-juju/3272/console

FAIL: watcher_test.go:482: watcherSuite.TestOfferStatusWatcher

[LOG] 0:00.010 DEBUG juju.environs.tools reading v1.* agent binaries
[LOG] 0:00.010 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-bionic-amd64
[LOG] 0:00.010 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-precise-amd64
[LOG] 0:00.013 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-trusty-amd64
[LOG] 0:00.015 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-xenial-amd64
[LOG] 0:00.029 INFO juju.environs.tools Writing tools/streams/v1/index2.json
[LOG] 0:00.029 INFO juju.environs.tools Writing tools/streams/v1/index.json
[LOG] 0:00.029 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju-released-tools.json
[LOG] 0:00.043 DEBUG juju.environs.tools reading v1.* agent binaries
[LOG] 0:00.043 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-bionic-amd64
[LOG] 0:00.046 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-precise-amd64
[LOG] 0:00.046 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-trusty-amd64
[LOG] 0:00.047 INFO juju.environs.testing uploading FAKE tools 2.5-beta1-xenial-amd64
[LOG] 0:00.049 DEBUG juju.environs.simplestreams skipping index "file:///tmp/juju-unittestfs/check-4481492911274223371/11/tools/streams/v1/index2.json" because of missing information: "content-download" data not found
[LOG] 0:00.049 DEBUG juju.environs.simplestreams skipping index "file:///tmp/juju-unittestfs/check-4481492911274223371/11/tools/streams/v1/index2.json" because of missing information: "content-download" data not found
[LOG] 0:00.050 DEBUG juju.environs.simplestreams skipping index "file:///tmp/juju-unittestfs/check-4481492911274223371/11/tools/streams/v1/index2.json" because of missing information: "content-download" data not found
[LOG] 0:00.050 INFO juju.environs.tools Writing tools/streams/v1/index2.json
[LOG] 0:00.050 INFO juju.environs.tools Writing tools/streams/v1/index.json
[LOG] 0:00.050 INFO juju.environs.tools Writing tools/streams/v1/com.ubuntu.juju-devel-tools.json
[LOG] 0:00.070 DEBUG juju.environs.bootstrap model "controller" supports application/machine networks: true
[LOG] 0:00.070 DEBUG juju.environs.bootstrap network management by juju enabled: true
[LOG] 0:00.070 INFO cmd Loading image metadata
[LOG] 0:00.070 INFO juju.environs.bootstrap looking for bootstrap agent binaries: version=2.5-beta1
[LOG] 0:00.070 DEBUG juju.environs.tools finding agent binaries in stream: "devel, proposed, released"
[LOG] 0:00.070 DEBUG juju.environs.tools reading agent binaries with major.minor version 2.5
[LOG] 0:00.070 DEBUG juju.environs.tools filtering agent binaries by version: 2.5-beta1
[LOG] 0:00.070 DEBUG juju.environs.tools filtering agent binaries by architecture: amd64
[LOG] 0:00.074 DEBUG juju.environs.simplestreams skipping index "file:///tmp/juju-unittestfs/check-4481492911274223371/11/tools/streams/v1/index2.sjson" because of missing information: "content-download" data not found
[LOG] 0:00.105 INFO juju.environs.bootstrap found 4 packaged agent binaries
[LOG] 0:00.106 INFO cmd Starting new instance for initial controller
[LOG] 0:00.106 INFO juju.provider.dummy would pick agent binaries from 2.5-beta1-bionic-amd64
[LOG] 0:00.106 INFO juju.provider.dummy creating bootstrap instance
[LOG] 0:00.106 INFO juju.environs.bootstrap newest version: 2.5-beta1
[LOG] 0:00.106 INFO juju.environs.bootstrap picked bootstrap agent binary version: 2.5-beta1
[LOG] 0:00.165 DEBUG juju.cloudconfig.instancecfg Setting numa ctl preference to false
[LOG] 0:00.165 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.268 DEBUG juju.state mongodb initialised
[LOG] 0:00.268 INFO juju.state running state anonymously; using unique store id
[LOG] 0:00.268 INFO juju.state starting standard state workers
[LOG] 0:00.268 INFO juju.state creating cloud image metadata storage
[LOG] 0:00.268 INFO juju.state started state for model-deadbeef-0bad-400d-8000-4b1d0d06f00d successfully
[LOG] 0:00.269 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.269 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.269 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.269 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.270 DEBUG juju.state.presence using $bit operations with Mongo 3.2.15
[LOG] 0:00.284 INFO juju.state initializing controller model deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:00.476 DEBUG juju.provider.dummy setting password for "admin" to "dummy-secret"
[LOG] 0:00.494 WARNING juju.apiserver.logsink Unable to prime /tmp/juju-unittestfs/check-4481492911274223371/10/logsink.log (proceeding anyway): chown /tmp/juju-unittestfs/check-4481492911274223371/10/logsink.log: operation not permitted
[LOG] 0:00.569 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.626 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.664 INFO juju.state starting standard state workers
[LOG] 0:00.664 INFO juju.state creating cloud image metadata storage
[LOG] 0:00.664 INFO juju.state started state for model-deadbeef-0bad-400d-8000-4b1d0d06f00d successfully
[LOG] 0:00.665 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.665 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.665 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.666 DEBUG juju.environs ControllerInstances returned: [localhost]
[LOG] 0:00.666 DEBUG juju.api looked up localhost -> [127.0.0.1]
[LOG] 0:00.702 DEBUG juju.state.presence using $bit operations with Mongo 3.2.15
[LOG] 0:00.714 DEBUG juju.apiserver [1] API connection from 127.0.0.1:37160
[LOG] 0:00.720 DEBUG juju.api successfully dialed "wss://localhost:33706/model/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:00.720 INFO juju.api connection established to "wss://localhost:33706/model/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:00.720 DEBUG juju.apiserver <- [1] {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
[LOG] 0:00.726 DEBUG juju.apiserver model login: user admin has "superuser" for controller; "admin" for model deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:00.726 DEBUG juju.apiserver model login: user-admin for deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:00.726 DEBUG juju.apiserver -> [1] 6.184372ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
[LOG] 0:00.727 DEBUG juju.state setting apiHostPorts: [[localhost:33706]]
[LOG] 0:00.728 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.742 DEBUG juju.mongo dialled mongodb server at "127.0.0.1:35329"
[LOG] 0:00.760 DEBUG juju.state setting apiHostPortsForAgents: [[localhost:33706]]
[LOG] 0:00.795 INFO juju.state new machine "0" has preferred addresses: private "", public ""
[LOG] 0:00.870 DEBUG juju.environs ControllerInstances returned: [localhost]
[LOG] 0:00.871 DEBUG juju.api looked up localhost -> [127.0.0.1]
[LOG] 0:00.882 DEBUG juju.apiserver [2] API connection from 127.0.0.1:37246
[LOG] 0:00.883 DEBUG juju.api successfully dialed "wss://localhost:33706/model/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:00.883 INFO juju.api connection established to "wss://localhost:33706/model/deadbeef-0bad-400d-8000-4b1d0d06f00d/api"
[LOG] 0:00.883 DEBUG juju.apiserver <- [2] {"request-id":1,"type":"Admin","version":3,"request":"Login","params":"'params redacted'"}
[LOG] 0:01.629 INFO juju.apiserver.connection agent login: machine-0 for deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:01.629 DEBUG juju.apiserver model login: machine-0 for deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:01.630 DEBUG juju.apiserver -> [2] 746.478684ms {"request-id":1,"response":"'body redacted'"} Admin[""].Login
[LOG] 0:01.638 DEBUG juju.charm charm is not in revision control directory
[LOG] 0:01.638 WARNING juju.charm making "/workspace/src/github.com/juju/juju/testcharms/charm-repo/quantal/mysql/hooks/install" executable in charm
[LOG] 0:01.639 WARNING juju.charm making "/workspace/src/github.com/juju/juju/testcharms/charm-repo/quantal/mysql/hooks/update-status" executable in charm
[LOG] 0:01.662 DEBUG juju.storage resource catalog entry created with id "f3ae836eb017e52d588f7850336f96abd61939fbbfd69f1d65da53e19894b5dacfeea429939c586d509eebccfca5834f"
[LOG] 0:01.717 DEBUG juju.storage managed resource entry created with path "buckets/deadbeef-0bad-400d-8000-4b1d0d06f00d/charms/local:quantal/mysql-1-586d134ba8bc2624838be7c714d032339931d9074623043a06673c3f5ced1f52" -> "f3ae836eb017e52d588f7850336f96abd61939fbbfd69f1d65da53e19894b5dacfeea429939c586d509eebccfca5834f"
[LOG] 0:01.751 DEBUG juju.state merged endpoint bindings modified: true, default: map[server-admin: server:], old: map[], new: map[], result: map[server: server-admin:]
[LOG] 0:01.946 DEBUG bakery.mgostorage root key cache miss
[LOG] 0:01.946 INFO bakery.mgostorage new root key id "ef96c592701405c29033830d213cc1ba"; created 2018-08-31 04:40:10.330928562 +0000 UTC m=+8.983565783; expires 2018-08-31 04:40:10.330928562 +0000 UTC m=+8.983565783
[LOG] 0:01.947 DEBUG juju.apiserver <- [2] machine-0 {"request-id":2,"type":"CrossModelRelations","version":1,"request":"WatchOfferStatus","params":"'params redacted'"}
[LOG] 0:01.948 DEBUG juju.apiserver.common.crossmodel check 1 macaroons with required attrs: map[source-model-uuid:deadbeef-0bad-400d-8000-4b1d0d06f00d offer-uuid:1c400128-c035-43a6-8562-fb18199a7092]
[LOG] 0:01.948 DEBUG juju.apiserver.common.crossmodel - mac ef96c592701405c29033830d213cc1ba-aad7cb1798ec2aa6f558e3ed5e24a306
[LOG] 0:01.948 DEBUG juju.apiserver.common.crossmodel check macaroons with declared attrs: map[source-model-uuid:deadbeef-0bad-400d-8000-4b1d0d06f00d offer-uuid:1c400128-c035-43a6-8562-fb18199a7092 username:fred]
[LOG] 0:01.948 INFO bakery.mgostorage cache miss for "ef96c592701405c29033830d213cc1ba"
[LOG] 0:01.967 DEBUG juju.apiserver.common.crossmodel generating discharge macaroon because: verification failed: macaroon not found in storage
[LOG] 0:01.967 DEBUG bakery.mgostorage root key cache miss
[LOG] 0:01.968 INFO bakery.mgostorage new root key id "b707430c6fe3d801edc324a862d4e964"; created 2018-08-31 04:40:10.352487807 +0000 UTC m=+9.005125016; expires 2018-08-31 04:46:10.352487807 +0000 UTC m=+369.005125016
[LOG] 0:01.968 DEBUG juju.apiserver -> [2] machine-0 21.289661ms {"request-id":2,"response":"'body redacted'"} CrossModelRelations[""].WatchOfferStatus
[LOG] 0:01.969 DEBUG juju.api.crossmodelrelations attempting to discharge macaroon due to error: verification failed: macaroon not found in storage
[LOG] 0:01.969 DEBUG httpbakery postFormJSON to https://localhost:33706/offeraccess/discharge; vals: url.Values{"id":[]string{"eyJUaGlyZFBhcnR5UHVibGljS2V5IjoiczlBNFpPSDF0RHZqTTdwU2s4aVhvQzR1OFFvVEpENUpVQ2VGamZlNjJSZz0iLCJGaXJzdFBhcnR5UHVibGljS2V5IjoiVzhtTXorVjlISGNHZXNRMUI1OERPTzFSMDY3VW1iTW83Tk8zTlR5TmdFdz0iLCJOb25jZSI6InZ1MWxGQ054Q0JNMXZ3WEZ6c0JkeWhxb2cyYjZlZXQxIiwiSWQiOiJoelk0RzlEbkNNbDlGbGxyeGpqUUptU3hXQ3dZVUd1bUYvaDJ4SGtQd2k3V1c3NXUrVW96bGVsWXdianN2eGExenN4cEFRN01EaFZLamVRKzJvRDdKN01OYUVUbHh5c3lQM0NTQU14Rjgvck8zUTJyY0kxQndrV3gzQTAva0dycEhsYk1jWmN4Q3VBQ0NFN2RwQjFxcU9NaGE4TlZ6QjNkN2IzZjlmVU9jQUZSbkgybFZXa2ZiY3RJV0FiajR5R3lXUjVOZkNNV254WDQrT1BscUE0bXpRb1dzTDhVR3dxTGdrek5oVXAvQ3RGV2dBKzB2eE9lTEZ0Q2tuRnR3MzUwNXNrUDZsSXo2R01YK0Z4b0V0K1RZaC9SQnBaUWFScGRHUUhmalRFSVUrcElhVEIxM3YyODRFMk9EN3FPT2FLVE9ERGMzVVcvR2ZjcWxUejZOc2VmQWd1cXZRbkNBUDgwbG5NVE1uQUhKc1lYSXpOZjB6VzFIY1NuUTFWY3lndVYxdnJxWFZ0QXVaQjN4RWpxY0NiNlYwNDMyVERtTW94Ty8zbz0ifQ=="}}
[LOG] 0:01.969 DEBUG httpbakery client do POST https://localhost:33706/offeraccess/discharge {
[LOG] 0:01.986 DEBUG httpbakery dischargeHandler.serveDischarge {
[LOG] 0:01.986 INFO bakery server attempting to discharge "eyJUaGlyZFBhcnR5UHVibGljS2V5IjoiczlBNFpPSDF0RHZqTTdwU2s4aVhvQzR1OFFvVEpENUpVQ2VGamZlNjJSZz0iLCJGaXJzdFBhcnR5UHVibGljS2V5IjoiVzhtTXorVjlISGNHZXNRMUI1OERPTzFSMDY3VW1iTW83Tk8zTlR5TmdFdz0iLCJOb25jZSI6InZ1MWxGQ054Q0JNMXZ3WEZ6c0JkeWhxb2cyYjZlZXQxIiwiSWQiOiJoelk0RzlEbkNNbDlGbGxyeGpqUUptU3hXQ3dZVUd1bUYvaDJ4SGtQd2k3V1c3NXUrVW96bGVsWXdianN2eGExenN4cEFRN01EaFZLamVRKzJvRDdKN01OYUVUbHh5c3lQM0NTQU14Rjgvck8zUTJyY0kxQndrV3gzQTAva0dycEhsYk1jWmN4Q3VBQ0NFN2RwQjFxcU9NaGE4TlZ6QjNkN2IzZjlmVU9jQUZSbkgybFZXa2ZiY3RJV0FiajR5R3lXUjVOZkNNV254WDQrT1BscUE0bXpRb1dzTDhVR3dxTGdrek5oVXAvQ3RGV2dBKzB2eE9lTEZ0Q2tuRnR3MzUwNXNrUDZsSXo2R01YK0Z4b0V0K1RZaC9SQnBaUWFScGRHUUhmalRFSVUrcElhVEIxM3YyODRFMk9EN3FPT2FLVE9ERGMzVVcvR2ZjcWxUejZOc2VmQWd1cXZRbkNBUDgwbG5NVE1uQUhKc1lYSXpOZjB6VzFIY1NuUTFWY3lndVYxdnJxWFZ0QXVaQjN4RWpxY0NiNlYwNDMyVERtTW94Ty8zbz0ifQ=="
[LOG] 0:01.987 DEBUG juju.apiserver check third party caveat &bakery.ThirdPartyCaveatInfo{Condition:"has-offer-permission source-model-uuid: deadbeef-0bad-400d-8000-4b1d0d06f00d\nusername: fred\noffer-uuid: 1c400128-c035-43a6-8562-fb18199a7092\nrelation-key: \"\"\npermission: consume\n", FirstPartyPublicKey:bakery.PublicKey{Key:bakery.Key{0x5b, 0xc9, 0x8c, 0xcf, 0xe5, 0x7d, 0x1c, 0x77, 0x6, 0x7a, 0xc4, 0x35, 0x7, 0x9f, 0x3, 0x38, 0xed, 0x51, 0xd3, 0xae, 0xd4, 0x99, 0xb3, 0x28, 0xec, 0xd3, 0xb7, 0x35, 0x3c, 0x8d, 0x80, 0x4c}}, ThirdPartyKeyPair:bakery.KeyPair{Public:bakery.PublicKey{Key:bakery.Key{0xb3, 0xd0, 0x38, 0x64, 0xe1, 0xf5, 0xb4, 0x3b, 0xe3, 0x33, 0xba, 0x52, 0x93, 0xc8, 0x97, 0xa0, 0x2e, 0x2e, 0xf1, 0xa, 0x13, 0x24, 0x3e, 0x49, 0x50, 0x27, 0x85, 0x8d, 0xf7, 0xba, 0xd9, 0x18}}, Private:bakery.PrivateKey{Key:bakery.Key{0x37, 0x53, 0xb0, 0x4a, 0xfe, 0xa, 0x14, 0xf8, 0xd7, 0x5a, 0x2, 0xa9, 0xaf, 0xef, 0xcc, 0x24, 0xf3, 0x92, 0xa0, 0xe4, 0xaf, 0x53, 0xbe, 0x2c, 0x62, 0xcf, 0xd2, 0x96, 0xed, 0x36, 0x7f, 0xb8}}}, RootKey:[]uint8{0xaf, 0xe, 0xbb, 0x43, 0xc, 0x50, 0x34, 0x90, 0xf4, 0x40, 0x0, 0xe, 0x94, 0xb7, 0x66, 0xc3, 0xb0, 0x67, 0x35, 0x6, 0xa0, 0xf0, 0xf7, 0x56}, CaveatId:[]uint8{0x65, 0x79, 0x4a, 0x55, 0x61, 0x47, 0x6c, 0x79, 0x5a, 0x46, 0x42, 0x68, 0x63, 0x6e, 0x52, 0x35, 0x55, 0x48, 0x56, 0x69, 0x62, 0x47, 0x6c, 0x6a, 0x53, 0x32, 0x56, 0x35, 0x49, 0x6a, 0x6f, 0x69, 0x63, 0x7a, 0x6c, 0x42, 0x4e, 0x46, 0x70, 0x50, 0x53, 0x44, 0x46, 0x30, 0x52, 0x48, 0x5a, 0x71, 0x54, 0x54, 0x64, 0x77, 0x55, 0x32, 0x73, 0x34, 0x61, 0x56, 0x68, 0x76, 0x51, 0x7a, 0x52, 0x31, 0x4f, 0x46, 0x46, 0x76, 0x56, 0x45, 0x70, 0x45, 0x4e, 0x55, 0x70, 0x56, 0x51, 0x32, 0x56, 0x47, 0x61, 0x6d, 0x5a, 0x6c, 0x4e, 0x6a, 0x4a, 0x53, 0x5a, 0x7a, 0x30, 0x69, 0x4c, 0x43, 0x4a, 0x47, 0x61, 0x58, 0x4a, 0x7a, 0x64, 0x46, 0x42, 0x68, 0x63, 0x6e, 0x52, 0x35, 0x55, 0x48, 0x56, 0x69, 0x62, 0x47, 0x6c, 0x6a, 0x53, 0x32, 0x56, 0x35, 0x49, 0x6a, 0x6f, 0x69, 0x56, 0x7a, 0x68, 0x74, 0x54, 0x58, 0x6f, 0x72, 0x56, 0x6a, 0x6c, 0x49, 0x53, 0x47, 0x4e, 0x48, 0x5a, 0x58, 0x4e, 0x52, 0x4d, 0x55, 0x49, 0x31, 0x4f, 0x45, 0x52, 0x50, 0x54, 0x7a, 0x46, 0x53, 0x4d, 0x44, 0x59, 0x33, 0x56, 0x57, 0x31, 0x69, 0x54, 0x57, 0x38, 0x33, 0x54, 0x6b, 0x38, 0x7a, 0x54, 0x6c, 0x52, 0x35, 0x54, 0x6d, 0x64, 0x46, 0x64, 0x7a, 0x30, 0x69, 0x4c, 0x43, 0x4a, 0x4f, 0x62, 0x32, 0x35, 0x6a, 0x5a, 0x53, 0x49, 0x36, 0x49, 0x6e, 0x5a, 0x31, 0x4d, 0x57, 0x78, 0x47, 0x51, 0x30, 0x35, 0x34, 0x51, 0x30, 0x4a, 0x4e, 0x4d, 0x58, 0x5a, 0x33, 0x57, 0x45, 0x5a, 0x36, 0x63, 0x30, 0x4a, 0x6b, 0x65, 0x57, 0x68, 0x78, 0x62, 0x32, 0x63, 0x79, 0x59, 0x6a, 0x5a, 0x6c, 0x5a, 0x58, 0x51, 0x78, 0x49, 0x69, 0x77, 0x69, 0x53, 0x57, 0x51, 0x69, 0x4f, 0x69, 0x4a, 0x6f, 0x65, 0x6c, 0x6b, 0x30, 0x52, 0x7a, 0x6c, 0x45, 0x62, 0x6b, 0x4e, 0x4e, 0x62, 0x44, 0x6c, 0x47, 0x62, 0x47, 0x78, 0x79, 0x65, 0x47, 0x70, 0x71, 0x55, 0x55, 0x70, 0x74, 0x55, 0x33, 0x68, 0x58, 0x51, 0x33, 0x64, 0x5a, 0x56, 0x55, 0x64, 0x31, 0x62, 0x55, 0x59, 0x76, 0x61, 0x44, 0x4a, 0x34, 0x53, 0x47, 0x74, 0x51, 0x64, 0x32, 0x6b, 0x33, 0x56, 0x31, 0x63, 0x33, 0x4e, 0x58, 0x55, 0x72, 0x56, 0x57, 0x39, 0x36, 0x62, 0x47, 0x56, 0x73, 0x57, 0x58, 0x64, 0x69, 0x61, 0x6e, 0x4e, 0x32, 0x65, 0x47, 0x45, 0x78, 0x65, 0x6e, 0x4e, 0x34, 0x63, 0x45, 0x46, 0x52, 0x4e, 0x30, 0x31, 0x45, 0x61, 0x46, 0x5a, 0x4c, 0x61, 0x6d, 0x56, 0x52, 0x4b, 0x7a, 0x4a, 0x76, 0x52, 0x44, 0x64, 0x4b, 0x4e, 0x30, 0x31, 0x4f, 0x59, 0x55, 0x56, 0x55, 0x62, 0x48, 0x68, 0x35, 0x63, 0x33, 0x6c, 0x51, 0x4d, 0x30, 0x4e, 0x54, 0x51, 0x55, 0x31, 0x34, 0x52, 0x6a, 0x67, 0x76, 0x63, 0x6b, 0x38, 0x7a, 0x55, 0x54, 0x4a, 0x79, 0x59, 0x30, 0x6b, 0x78, 0x51, 0x6e, 0x64, 0x72, 0x56, 0x33, 0x67, 0x7a, 0x51, 0x54, 0x41, 0x76, 0x61, 0x30, 0x64, 0x79, 0x63, 0x45, 0x68, 0x73, 0x59, 0x6b, 0x31, 0x6a, 0x57, 0x6d, 0x4e, 0x34, 0x51, 0x33, 0x56, 0x42, 0x51, 0x30, 0x4e, 0x46, 0x4e, 0x32, 0x52, 0x77, 0x51, 0x6a, 0x46, 0x78, 0x63, 0x55, 0x39, 0x4e, 0x61, 0x47, 0x45, 0x34, 0x54, 0x6c, 0x5a, 0x36, 0x51, 0x6a, 0x4e, 0x6b, 0x4e, 0x32, 0x49, 0x7a, 0x5a, 0x6a, 0x6c, 0x6d, 0x56, 0x55, 0x39, 0x6a, 0x51, 0x55, 0x5a, 0x53, 0x62, 0x6b,
0x67, 0x79, 0x62, 0x46, 0x5a, 0x58, 0x61, 0x32, 0x5a, 0x69, 0x59, 0x33, 0x52, 0x4a, 0x56, 0x30, 0x46, 0x69, 0x61, 0x6a, 0x52, 0x35, 0x52, 0x33, 0x6c, 0x58, 0x55, 0x6a, 0x56, 0x4f, 0x5a, 0x6b, 0x4e, 0x4e, 0x56, 0x32, 0x35, 0x34, 0x57, 0x44, 0x51, 0x72, 0x54, 0x31, 0x42, 0x73, 0x63, 0x55, 0x45, 0x30, 0x62, 0x58, 0x70, 0x52, 0x62, 0x31, 0x64, 0x7a, 0x54, 0x44, 0x68, 0x56, 0x52, 0x33, 0x64, 0x78, 0x54, 0x47, 0x64, 0x72, 0x65, 0x6b, 0x35, 0x6f, 0x56, 0x58, 0x41, 0x76, 0x51, 0x33, 0x52, 0x47, 0x56, 0x32, 0x64, 0x42, 0x4b, 0x7a, 0x42, 0x32, 0x65, 0x45, 0x39, 0x6c, 0x54, 0x45, 0x5a, 0x30, 0x51, 0x32, 0x74, 0x75, 0x52, 0x6e, 0x52, 0x33, 0x4d, 0x7a, 0x55, 0x77, 0x4e, 0x58, 0x4e, 0x72, 0x55, 0x44, 0x5a, 0x73, 0x53, 0x58, 0x6f, 0x32, 0x52, 0x30, 0x31, 0x59, 0x4b, 0x30, 0x5a, 0x34, 0x62, 0x30, 0x56, 0x30, 0x4b, 0x31, 0x52, 0x5a, 0x61, 0x43, 0x39, 0x53, 0x51, 0x6e, 0x42, 0x61, 0x55, 0x57, 0x46, 0x53, 0x63, 0x47, 0x52, 0x48, 0x55, 0x55, 0x68, 0x6d, 0x61, 0x6c, 0x52, 0x46, 0x53, 0x56, 0x55, 0x72, 0x63, 0x45, 0x6c, 0x68, 0x56, 0x45, 0x49, 0x78, 0x4d, 0x33, 0x59, 0x79, 0x4f, 0x44, 0x52, 0x46, 0x4d, 0x6b, 0x39, 0x45, 0x4e, 0x33, 0x46, 0x50, 0x54, 0x32, 0x46, 0x4c, 0x56, 0x45, 0x39, 0x45, 0x52, 0x47, 0x4d, 0x7a, 0x56, 0x56, 0x63, 0x76, 0x52, 0x32, 0x5a, 0x6a, 0x63, 0x57, 0x78, 0x55, 0x65, 0x6a, 0x5a, 0x4f, 0x63, 0x32, 0x56, 0x6d, 0x51, 0x57, 0x64, 0x31, 0x63, 0x58, 0x5a, 0x52, 0x62, 0x6b, 0x4e, 0x42, 0x55, 0x44, 0x67, 0x77, 0x62, 0x47, 0x35, 0x4e, 0x56, 0x45, 0x31, 0x75, 0x51, 0x55, 0x68, 0x4b, 0x63, 0x31, 0x6c, 0x59, 0x53, 0x58, 0x70, 0x4f, 0x5a, 0x6a, 0x42, 0x36, 0x56, 0x7a, 0x46, 0x49, 0x59, 0x31, 0x4e, 0x75, 0x55, 0x54, 0x46, 0x57, 0x59, 0x33, 0x6c, 0x6e, 0x64, 0x56, 0x59, 0x78, 0x64, 0x6e, 0x4a, 0x78, 0x57, 0x46, 0x5a, 0x30, 0x51, 0x58, 0x56, 0x61, 0x51, 0x6a, 0x4e, 0x34, 0x52, 0x57, 0x70, 0x78, 0x59, 0x30, 0x4e, 0x69, 0x4e, 0x6c, 0x59, 0x77, 0x4e, 0x44, 0x4d, 0x79, 0x56, 0x45, 0x52, 0x74, 0x54, 0x57, 0x39, 0x34, 0x54, 0x79, 0x38, 0x7a, 0x62, 0x7a, 0x30, 0x69, 0x66, 0x51, 0x3d, 0x3d}, MacaroonId:[]uint8{0x65, 0x79, 0x4a, 0x55, 0x61, 0x47, 0x6c, 0x79, 0x5a, 0x46, 0x42, 0x68, 0x63, 0x6e, 0x52, 0x35, 0x55, 0x48, 0x56, 0x69, 0x62, 0x47, 0x6c, 0x6a, 0x53, 0x32, 0x56, 0x35, 0x49, 0x6a, 0x6f, 0x69, 0x63, 0x7a, 0x6c, 0x42, 0x4e, 0x46, 0x70, 0x50, 0x53, 0x44, 0x46, 0x30, 0x52, 0x48, 0x5a, 0x71, 0x54, 0x54, 0x64, 0x77, 0x55, 0x32, 0x73, 0x34, 0x61, 0x56, 0x68, 0x76, 0x51, 0x7a, 0x52, 0x31, 0x4f, 0x46, 0x46, 0x76, 0x56, 0x45, 0x70, 0x45, 0x4e, 0x55, 0x70, 0x56, 0x51, 0x32, 0x56, 0x47, 0x61, 0x6d, 0x5a, 0x6c, 0x4e, 0x6a, 0x4a, 0x53, 0x5a, 0x7a, 0x30, 0x69, 0x4c, 0x43, 0x4a, 0x47, 0x61, 0x58, 0x4a, 0x7a, 0x64, 0x46, 0x42, 0x68, 0x63, 0x6e, 0x52, 0x35, 0x55, 0x48, 0x56, 0x69, 0x62, 0x47, 0x6c, 0x6a, 0x53, 0x32, 0x56, 0x35, 0x49, 0x6a, 0x6f, 0x69, 0x56, 0x7a, 0x68, 0x74, 0x54, 0x58, 0x6f, 0x72, 0x56, 0x6a, 0x6c, 0x49, 0x53, 0x47, 0x4e, 0x48, 0x5a, 0x58, 0x4e, 0x52, 0x4d, 0x55, 0x49, 0x31, 0x4f, 0x45, 0x52, 0x50, 0x54, 0x7a, 0x46, 0x53, 0x4d, 0x44, 0x59, 0x33, 0x56, 0x57, 0x31, 0x69, 0x54, 0x57, 0x38, 0x33, 0x54, 0x6b, 0x38, 0x7a, 0x54, 0x6c, 0x52, 0x35, 0x54, 0x6d, 0x64, 0x46, 0x64, 0x7a, 0x30, 0x69, 0x4c, 0x43, 0x4a, 0x4f, 0x62, 0x32, 0x35, 0x6a, 0x5a, 0x53, 0x49, 0x36, 0x49, 0x6e, 0x5a, 0x31, 0x4d, 0x57, 0x78, 0x47, 0x51, 0x30, 0x35, 0x34, 0x51, 0x30, 0x4a, 0x4e, 0x4d, 0x58, 0x5a, 0x33, 0x57, 0x45, 0x5a, 0x36, 0x63, 0x30, 0x4a, 0x6b, 0x65, 0x57, 0x68, 0x78, 0x62, 0x32, 0x63, 0x79, 0x59, 0x6a, 0x5a, 0x6c, 0x5a, 0x58, 0x51, 0x78, 0x49, 0x69, 0x77, 0x69, 0x53, 0x57, 0x51, 0x69, 0x4f, 0x69, 0x4a, 0x6f, 0x65, 0x6c, 0x6b, 0x30, 0x52, 0x7a, 0x6c, 0x45, 0x62, 0x6b, 0x4e, 0x4e, 0x62, 0x44, 0x6c, 0x47, 0x62, 0x47, 0x78, 0x79, 0x65, 0x47, 0x70, 0x71, 0x55, 0x55, 0x70, 0x74, 0x55, 0x33, 0x68, 0x58, 0x51, 0x33, 0x64, 0x5a, 0x56, 0x55, 0x64, 0x31, 0x62, 0x55, 0x59, 0x76, 0x61, 0x44, 0x4a, 0x34, 0x53, 0x47, 0x74, 0x51, 0x64, 0x32, 0x6b, 0x33, 0x56, 0x31, 0x63, 0x33, 0x4e, 0x58, 0x55, 0x72, 0x56, 0x57, 0x39, 0x36, 0x62, 0x47, 0x56, 0x73, 0x57, 0x58, 0x64, 0x69, 0x61, 0x6e, 0x4e, 0x32, 0x65, 0x47, 0x45, 0x78, 0x65, 0x6e, 0x4e, 0x34, 0x63, 0x45, 0x46, 0x52, 0x4e, 0x
30, 0x31, 0x45, 0x61, 0x46, 0x5a, 0x4c, 0x61, 0x6d, 0x56, 0x52, 0x4b, 0x7a, 0x4a, 0x76, 0x52, 0x44, 0x64, 0x4b, 0x4e, 0x30, 0x31, 0x4f, 0x59, 0x55, 0x56, 0x55, 0x62, 0x48, 0x68, 0x35, 0x63, 0x33, 0x6c, 0x51, 0x4d, 0x30, 0x4e, 0x54, 0x51, 0x55, 0x31, 0x34, 0x52, 0x6a, 0x67, 0x76, 0x63, 0x6b, 0x38, 0x7a, 0x55, 0x54, 0x4a, 0x79, 0x59, 0x30, 0x6b, 0x78, 0x51, 0x6e, 0x64, 0x72, 0x56, 0x33, 0x67, 0x7a, 0x51, 0x54, 0x41, 0x76, 0x61, 0x30, 0x64, 0x79, 0x63, 0x45, 0x68, 0x73, 0x59, 0x6b, 0x31, 0x6a, 0x57, 0x6d, 0x4e, 0x34, 0x51, 0x33, 0x56, 0x42, 0x51, 0x30, 0x4e, 0x46, 0x4e, 0x32, 0x52, 0x77, 0x51, 0x6a, 0x46, 0x78, 0x63, 0x55, 0x39, 0x4e, 0x61, 0x47, 0x45, 0x34, 0x54, 0x6c, 0x5a, 0x36, 0x51, 0x6a, 0x4e, 0x6b, 0x4e, 0x32, 0x49, 0x7a, 0x5a, 0x6a, 0x6c, 0x6d, 0x56, 0x55, 0x39, 0x6a, 0x51, 0x55, 0x5a, 0x53, 0x62, 0x6b, 0x67, 0x79, 0x62, 0x46, 0x5a, 0x58, 0x61, 0x32, 0x5a, 0x69, 0x59, 0x33, 0x52, 0x4a, 0x56, 0x30, 0x46, 0x69, 0x61, 0x6a, 0x52, 0x35, 0x52, 0x33, 0x6c, 0x58, 0x55, 0x6a, 0x56, 0x4f, 0x5a, 0x6b, 0x4e, 0x4e, 0x56, 0x32, 0x35, 0x34, 0x57, 0x44, 0x51, 0x72, 0x54, 0x31, 0x42, 0x73, 0x63, 0x55, 0x45, 0x30, 0x62, 0x58, 0x70, 0x52, 0x62, 0x31, 0x64, 0x7a, 0x54, 0x44, 0x68, 0x56, 0x52, 0x33, 0x64, 0x78, 0x54, 0x47, 0x64, 0x72, 0x65, 0x6b, 0x35, 0x6f, 0x56, 0x58, 0x41, 0x76, 0x51, 0x33, 0x52, 0x47, 0x56, 0x32, 0x64, 0x42, 0x4b, 0x7a, 0x42, 0x32, 0x65, 0x45, 0x39, 0x6c, 0x54, 0x45, 0x5a, 0x30, 0x51, 0x32, 0x74, 0x75, 0x52, 0x6e, 0x52, 0x33, 0x4d, 0x7a, 0x55, 0x77, 0x4e, 0x58, 0x4e, 0x72, 0x55, 0x44, 0x5a, 0x73, 0x53, 0x58, 0x6f, 0x32, 0x52, 0x30, 0x31, 0x59, 0x4b, 0x30, 0x5a, 0x34, 0x62, 0x30, 0x56, 0x30, 0x4b, 0x31, 0x52, 0x5a, 0x61, 0x43, 0x39, 0x53, 0x51, 0x6e, 0x42, 0x61, 0x55, 0x57, 0x46, 0x53, 0x63, 0x47, 0x52, 0x48, 0x55, 0x55, 0x68, 0x6d, 0x61, 0x6c, 0x52, 0x46, 0x53, 0x56, 0x55, 0x72, 0x63, 0x45, 0x6c, 0x68, 0x56, 0x45, 0x49, 0x78, 0x4d, 0x33, 0x59, 0x79, 0x4f, 0x44, 0x52, 0x46, 0x4d, 0x6b, 0x39, 0x45, 0x4e, 0x33, 0x46, 0x50, 0x54, 0x32, 0x46, 0x4c, 0x56, 0x45, 0x39, 0x45, 0x52, 0x47, 0x4d, 0x7a, 0x56, 0x56, 0x63, 0x76, 0x52, 0x32, 0x5a, 0x6a, 0x63, 0x57, 0x78, 0x55, 0x65, 0x6a, 0x5a, 0x4f, 0x63, 0x32, 0x56, 0x6d, 0x51, 0x57, 0x64, 0x31, 0x63, 0x58, 0x5a, 0x52, 0x62, 0x6b, 0x4e, 0x42, 0x55, 0x44, 0x67, 0x77, 0x62, 0x47, 0x35, 0x4e, 0x56, 0x45, 0x31, 0x75, 0x51, 0x55, 0x68, 0x4b, 0x63, 0x31, 0x6c, 0x59, 0x53, 0x58, 0x70, 0x4f, 0x5a, 0x6a, 0x42, 0x36, 0x56, 0x7a, 0x46, 0x49, 0x59, 0x31, 0x4e, 0x75, 0x55, 0x54, 0x46, 0x57, 0x59, 0x33, 0x6c, 0x6e, 0x64, 0x56, 0x59, 0x78, 0x64, 0x6e, 0x4a, 0x78, 0x57, 0x46, 0x5a, 0x30, 0x51, 0x58, 0x56, 0x61, 0x51, 0x6a, 0x4e, 0x34, 0x52, 0x57, 0x70, 0x78, 0x59, 0x30, 0x4e, 0x69, 0x4e, 0x6c, 0x59, 0x77, 0x4e, 0x44, 0x4d, 0x79, 0x56, 0x45, 0x52, 0x74, 0x54, 0x57, 0x39, 0x34, 0x54, 0x79, 0x38, 0x7a, 0x62, 0x7a, 0x30, 0x69, 0x66, 0x51, 0x3d, 0x3d}}
[LOG] 0:01.987 DEBUG juju.apiserver.common.crossmodel offer access caveat details: {SourceModelUUID:deadbeef-0bad-400d-8000-4b1d0d06f00d User:fred OfferUUID:1c400128-c035-43a6-8562-fb18199a7092 Relation: Permission:consume}
[LOG] 0:01.987 DEBUG juju.apiserver.common.crossmodel authenticate local offer access: &{SourceModelUUID:deadbeef-0bad-400d-8000-4b1d0d06f00d User:fred OfferUUID:1c400128-c035-43a6-8562-fb18199a7092 Relation: Permission:consume}
[LOG] 0:01.990 DEBUG httpbakery }
[LOG] 0:01.990 DEBUG httpbakery serveDischarge -> &httpbakery.dischargeResponse{Macaroon:(*macaroon.Macaroon)(0xc4202cafc0)}
[LOG] 0:01.990 DEBUG httpbakery } -> error <nil>
[LOG] 0:01.991 DEBUG juju.apiserver <- [2] machine-0 {"request-id":3,"type":"CrossModelRelations","version":1,"request":"WatchOfferStatus","params":"'params redacted'"}
[LOG] 0:01.991 DEBUG juju.apiserver.common.crossmodel check 2 macaroons with required attrs: map[source-model-uuid:deadbeef-0bad-400d-8000-4b1d0d06f00d offer-uuid:1c400128-c035-43a6-8562-fb18199a7092]
[LOG] 0:01.991 DEBUG juju.apiserver.common.crossmodel - mac b707430c6fe3d801edc324a862d4e964-a9d7cb1798ec2aa6f558e3ed5e24a306
[LOG] 0:01.991 DEBUG juju.apiserver.common.crossmodel - mac eyJUaGlyZFBhcnR5UHVibGljS2V5IjoiczlBNFpPSDF0RHZqTTdwU2s4aVhvQzR1OFFvVEpENUpVQ2VGamZlNjJSZz0iLCJGaXJzdFBhcnR5UHVibGljS2V5IjoiVzhtTXorVjlISGNHZXNRMUI1OERPTzFSMDY3VW1iTW83Tk8zTlR5TmdFdz0iLCJOb25jZSI6InZ1MWxGQ054Q0JNMXZ3WEZ6c0JkeWhxb2cyYjZlZXQxIiwiSWQiOiJoelk0RzlEbkNNbDlGbGxyeGpqUUptU3hXQ3dZVUd1bUYvaDJ4SGtQd2k3V1c3NXUrVW96bGVsWXdianN2eGExenN4cEFRN01EaFZLamVRKzJvRDdKN01OYUVUbHh5c3lQM0NTQU14Rjgvck8zUTJyY0kxQndrV3gzQTAva0dycEhsYk1jWmN4Q3VBQ0NFN2RwQjFxcU9NaGE4TlZ6QjNkN2IzZjlmVU9jQUZSbkgybFZXa2ZiY3RJV0FiajR5R3lXUjVOZkNNV254WDQrT1BscUE0bXpRb1dzTDhVR3dxTGdrek5oVXAvQ3RGV2dBKzB2eE9lTEZ0Q2tuRnR3MzUwNXNrUDZsSXo2R01YK0Z4b0V0K1RZaC9SQnBaUWFScGRHUUhmalRFSVUrcElhVEIxM3YyODRFMk9EN3FPT2FLVE9ERGMzVVcvR2ZjcWxUejZOc2VmQWd1cXZRbkNBUDgwbG5NVE1uQUhKc1lYSXpOZjB6VzFIY1NuUTFWY3lndVYxdnJxWFZ0QXVaQjN4RWpxY0NiNlYwNDMyVERtTW94Ty8zbz0ifQ==
[LOG] 0:01.991 DEBUG juju.apiserver.common.crossmodel check macaroons with declared attrs: map[offer-uuid:1c400128-c035-43a6-8562-fb18199a7092 username:fred source-model-uuid:deadbeef-0bad-400d-8000-4b1d0d06f00d]
[LOG] 0:01.992 DEBUG juju.apiserver.common.crossmodel macaroon check ok, attr: map[source-model-uuid:deadbeef-0bad-400d-8000-4b1d0d06f00d offer-uuid:1c400128-c035-43a6-8562-fb18199a7092 username:fred]
[LOG] 0:02.062 DEBUG juju.apiserver -> [2] machine-0 70.604149ms {"request-id":3,"response":"'body redacted'"} CrossModelRelations[""].WatchOfferStatus
[LOG] 0:02.062 DEBUG juju.apiserver <- [2] machine-0 {"request-id":4,"type":"OfferStatusWatcher","version":1,"id":"1","request":"Next","params":"'params redacted'"}
[LOG] 0:02.178 DEBUG juju.apiserver -> [2] machine-0 115.922305ms {"request-id":4,"response":"'body redacted'"} OfferStatusWatcher["1"].Next
[LOG] 0:02.179 DEBUG juju.apiserver <- [2] machine-0 {"request-id":5,"type":"OfferStatusWatcher","version":1,"id":"1","request":"Next","params":"'params redacted'"}
[LOG] 0:02.193 DEBUG juju.apiserver -> [2] machine-0 14.707472ms {"request-id":5,"response":"'body redacted'"} OfferStatusWatcher["1"].Next
[LOG] 0:02.194 DEBUG juju.apiserver <- [2] machine-0 {"request-id":6,"type":"OfferStatusWatcher","version":1,"id":"1","request":"Next","params":"'params redacted'"}
watcher_test.go:494:
    assertChange(status.Waiting, "another message")
watcher_test.go:453:
    c.Fatalf("watcher sent unexpected change: (_, %v)", ok)
... Error: watcher sent unexpected change: (_, true)

[LOG] 0:02.219 DEBUG juju.apiserver <- [2] machine-0 {"request-id":7,"type":"OfferStatusWatcher","version":1,"id":"1","request":"Stop","params":"'params redacted'"}
[LOG] 0:02.219 DEBUG juju.apiserver -> [2] machine-0 140.257µs {"request-id":7,"response":"'body redacted'"} OfferStatusWatcher["1"].Stop
[LOG] 0:02.219 DEBUG juju.apiserver -> [2] machine-0 25.442193ms {"request-id":6,"error":"watcher has been stopped","error-code":"stopped","response":"'body redacted'"} OfferStatusWatcher["1"].Next
[LOG] 0:02.222 INFO juju.apiserver.connection agent disconnected: machine-0 for deadbeef-0bad-400d-8000-4b1d0d06f00d
[LOG] 0:02.222 DEBUG juju.apiserver [2] machine-0 API connection terminated after 1.339848645s
[LOG] 0:02.222 DEBUG juju.api RPC connection died
[LOG] 0:02.222 DEBUG juju.apiserver [1] user-admin API connection terminated after 1.508425842s
[LOG] 0:02.223 DEBUG juju.api RPC connection died
[LOG] 0:02.225 DEBUG juju.state closed state without error
[LOG] 0:02.225 INFO juju.provider.dummy reset model
[LOG] 0:02.225 DEBUG juju.provider.dummy closing httpServer
[LOG] 0:02.225 DEBUG juju.provider.dummy stopping apiServer
[LOG] 0:02.234 DEBUG juju.provider.dummy closing apiStatePool
[LOG] 0:02.236 DEBUG juju.state closed state without error
[LOG] 0:02.236 DEBUG juju.provider.dummy resetting MgoServer
[LOG] 0:02.238 INFO juju.testing reset successfully reset admin password
[LOG] 0:02.249 INFO juju.testing reset successfully reset admin password

Changed in juju:
status: Triaged → Incomplete
Revision history for this message
Tim Penhey (thumper) wrote :

This is one of those bugs that needs to be fixed in the apiserver code.

There are two pending events, each one gets the latest data and passes it on without checking to see if anything had changed.

Revision history for this message
Launchpad Janitor (janitor) wrote :

[Expired for juju because there has been no activity for 60 days.]

Changed in juju:
status: Incomplete → Expired
Revision history for this message
John A Meinel (jameinel) wrote :
Changed in juju:
status: Expired → Incomplete
Revision history for this message
Launchpad Janitor (janitor) wrote :

[Expired for juju because there has been no activity for 60 days.]

Changed in juju:
status: Incomplete → Expired
To post a comment you must log in.
This report contains Public information  
Everyone can see this information.

Other bug subscribers

Remote bug watches

Bug watches keep track of this bug in other bug trackers.