Closed Bug 1627379 Opened 4 years ago Closed 2 years ago

Intermittent TEST-UNEXPECTED-TIMEOUT | dom/push/test/xpcshell/test_unregister_invalid_json.js | Test timed out

Categories

(Core :: DOM: Push Subscriptions, defect)

defect
Not set
normal

Tracking

()

RESOLVED DUPLICATE of bug 1781451

People

(Reporter: intermittent-bug-filer, Unassigned)

References

(Blocks 1 open bug)

Details

(Keywords: intermittent-failure, Whiteboard: [stockwell disabled])

Attachments

(1 file)

Filed by: opoprus [at] mozilla.com
Parsed log: https://treeherder.mozilla.org/logviewer.html#?job_id=296181274&repo=mozilla-central
Full log: https://firefox-ci-tc.services.mozilla.com/api/queue/v1/task/DRQulKd3RiSdomG3hSpTEg/runs/0/artifacts/public/logs/live_backing.log


[task 2020-04-03T19:43:38.378Z] 19:43:38 INFO - TEST-START | dom/push/test/xpcshell/test_unregister_invalid_json.js
[task 2020-04-03T19:48:38.376Z] 19:48:38 WARNING - TEST-UNEXPECTED-TIMEOUT | dom/push/test/xpcshell/test_unregister_invalid_json.js | Test timed out
[task 2020-04-03T19:48:38.376Z] 19:48:38 INFO - TEST-INFO took 300000ms
[task 2020-04-03T19:48:38.377Z] 19:48:38 INFO - >>>>>>>
[task 2020-04-03T19:48:38.378Z] 19:48:38 INFO - (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2020-04-03T19:48:38.378Z] 19:48:38 INFO - (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2020-04-03T19:48:38.379Z] 19:48:38 INFO - (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2020-04-03T19:48:38.379Z] 19:48:38 INFO - running event loop
[task 2020-04-03T19:48:38.380Z] 19:48:38 INFO - dom/push/test/xpcshell/test_unregister_invalid_json.js | Starting test_unregister_invalid_json
[task 2020-04-03T19:48:38.380Z] 19:48:38 INFO - (xpcshell/head.js) | test test_unregister_invalid_json pending (2)
[task 2020-04-03T19:48:38.381Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.381Z] 19:48:38 INFO - PID 10724 | PushDB()
[task 2020-04-03T19:48:38.382Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.382Z] 19:48:38 INFO - PID 10724 | put()
[task 2020-04-03T19:48:38.383Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.383Z] 19:48:38 INFO - PID 10724 | - channelID = 87902e90-c57e-4d18-8354-013f4a556559
[task 2020-04-03T19:48:38.384Z] 19:48:38 INFO - PID 10724 | - pushEndpoint = https://example.org/update/1
[task 2020-04-03T19:48:38.384Z] 19:48:38 INFO - PID 10724 | - scope = https://example.edu/page/1
[task 2020-04-03T19:48:38.385Z] 19:48:38 INFO - PID 10724 | - originAttributes =
[task 2020-04-03T19:48:38.385Z] 19:48:38 INFO - PID 10724 | - version = 1
[task 2020-04-03T19:48:38.386Z] 19:48:38 INFO - PID 10724 | - quota = Infinity
[task 2020-04-03T19:48:38.386Z] 19:48:38 INFO - (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2020-04-03T19:48:38.387Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.387Z] 19:48:38 INFO - PID 10724 | put: Request successful. Updated record
[task 2020-04-03T19:48:38.388Z] 19:48:38 INFO - PID 10724 | 87902e90-c57e-4d18-8354-013f4a556559
[task 2020-04-03T19:48:38.388Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.389Z] 19:48:38 INFO - PID 10724 | put()
[task 2020-04-03T19:48:38.389Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.390Z] 19:48:38 INFO - PID 10724 | - channelID = 057caa8f-9b99-47ff-891c-adad18ce603e
[task 2020-04-03T19:48:38.390Z] 19:48:38 INFO - PID 10724 | - pushEndpoint = https://example.com/update/2
[task 2020-04-03T19:48:38.391Z] 19:48:38 INFO - PID 10724 | - scope = https://example.net/page/1
[task 2020-04-03T19:48:38.391Z] 19:48:38 INFO - PID 10724 | - originAttributes =
[task 2020-04-03T19:48:38.392Z] 19:48:38 INFO - PID 10724 | - version = 1
[task 2020-04-03T19:48:38.392Z] 19:48:38 INFO - PID 10724 | - quota = Infinity
[task 2020-04-03T19:48:38.393Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.393Z] 19:48:38 INFO - PID 10724 | put: Request successful. Updated record
[task 2020-04-03T19:48:38.394Z] 19:48:38 INFO - PID 10724 | 057caa8f-9b99-47ff-891c-adad18ce603e
[task 2020-04-03T19:48:38.394Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.394Z] 19:48:38 INFO - PID 10724 | init()
[task 2020-04-03T19:48:38.395Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.395Z] 19:48:38 INFO - PID 10724 | setState()
[task 2020-04-03T19:48:38.396Z] 19:48:38 INFO - PID 10724 | new state
[task 2020-04-03T19:48:38.396Z] 19:48:38 INFO - PID 10724 | 2
[task 2020-04-03T19:48:38.397Z] 19:48:38 INFO - PID 10724 | old state
[task 2020-04-03T19:48:38.397Z] 19:48:38 INFO - PID 10724 | 0
[task 2020-04-03T19:48:38.398Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.398Z] 19:48:38 INFO - PID 10724 | unregister()
[task 2020-04-03T19:48:38.399Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.399Z] 19:48:38 INFO - PID 10724 | - scope = https://example.edu/page/1
[task 2020-04-03T19:48:38.400Z] 19:48:38 INFO - PID 10724 | - originAttributes =
[task 2020-04-03T19:48:38.400Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.401Z] 19:48:38 INFO - PID 10724 | changeServerURL()
[task 2020-04-03T19:48:38.401Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.402Z] 19:48:38 INFO - PID 10724 | findService()
[task 2020-04-03T19:48:38.402Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.403Z] 19:48:38 INFO - PID 10724 | startService()
[task 2020-04-03T19:48:38.403Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.404Z] 19:48:38 INFO - PID 10724 | init()
[task 2020-04-03T19:48:38.404Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.405Z] 19:48:38 INFO - PID 10724 | startObservers()
[task 2020-04-03T19:48:38.405Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.405Z] 19:48:38 INFO - PID 10724 | dropExpiredRegistrations()
[task 2020-04-03T19:48:38.406Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.406Z] 19:48:38 INFO - PID 10724 | getAllExpired()
[task 2020-04-03T19:48:38.407Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.407Z] 19:48:38 INFO - PID 10724 | getAllByPushQuota()
[task 2020-04-03T19:48:38.408Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.408Z] 19:48:38 INFO - PID 10724 | changeStateConnectionEnabledEvent()
[task 2020-04-03T19:48:38.409Z] 19:48:38 INFO - PID 10724 | true
[task 2020-04-03T19:48:38.409Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.409Z] 19:48:38 INFO - PID 10724 | changeStateOfflineEvent()
[task 2020-04-03T19:48:38.410Z] 19:48:38 INFO - PID 10724 | false
[task 2020-04-03T19:48:38.410Z] 19:48:38 INFO - PID 10724 | console.debug: PushService:
[task 2020-04-03T19:48:38.411Z] 19:48:38 INFO - PID 10724 | setState()
[task 2020-04-03T19:48:38.411Z] 19:48:38 INFO - PID 10724 | new state
[task 2020-04-03T19:48:38.412Z] 19:48:38 INFO - PID 10724 | 5
[task 2020-04-03T19:48:38.412Z] 19:48:38 INFO - PID 10724 | old state
[task 2020-04-03T19:48:38.413Z] 19:48:38 INFO - PID 10724 | 2
[task 2020-04-03T19:48:38.414Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.414Z] 19:48:38 INFO - PID 10724 | connect()
[task 2020-04-03T19:48:38.414Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.415Z] 19:48:38 INFO - PID 10724 | - prototype Object
[task 2020-04-03T19:48:38.415Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.415Z] 19:48:38 INFO - PID 10724 | beginWSSetup()
[task 2020-04-03T19:48:38.415Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.415Z] 19:48:38 INFO - PID 10724 | beginWSSetup: Connecting to
[task 2020-04-03T19:48:38.415Z] 19:48:38 INFO - PID 10724 | wss://push.example.org/
[task 2020-04-03T19:48:38.416Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.417Z] 19:48:38 INFO - PID 10724 | getByIdentifiers()
[task 2020-04-03T19:48:38.417Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.417Z] 19:48:38 INFO - PID 10724 | - scope = https://example.edu/page/1
[task 2020-04-03T19:48:38.418Z] 19:48:38 INFO - PID 10724 | - originAttributes =
[task 2020-04-03T19:48:38.418Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.419Z] 19:48:38 INFO - PID 10724 | wsOnStart()
[task 2020-04-03T19:48:38.419Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.420Z] 19:48:38 INFO - PID 10724 | getAllUnexpired()
[task 2020-04-03T19:48:38.420Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.421Z] 19:48:38 INFO - PID 10724 | getAllByPushQuota()
[task 2020-04-03T19:48:38.421Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.422Z] 19:48:38 INFO - PID 10724 | delete()
[task 2020-04-03T19:48:38.423Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.423Z] 19:48:38 INFO - PID 10724 | unregister()
[task 2020-04-03T19:48:38.424Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.424Z] 19:48:38 INFO - PID 10724 | - pushEndpoint = https://example.org/update/1
[task 2020-04-03T19:48:38.425Z] 19:48:38 INFO - PID 10724 | - scope = https://example.edu/page/1
[task 2020-04-03T19:48:38.426Z] 19:48:38 INFO - PID 10724 | - originAttributes =
[task 2020-04-03T19:48:38.426Z] 19:48:38 INFO - PID 10724 | - pushCount = 0
[task 2020-04-03T19:48:38.427Z] 19:48:38 INFO - PID 10724 | - lastPush = 0
[task 2020-04-03T19:48:38.427Z] 19:48:38 INFO - PID 10724 | - p256dhPublicKey = undefined
[task 2020-04-03T19:48:38.428Z] 19:48:38 INFO - PID 10724 | - p256dhPrivateKey = undefined
[task 2020-04-03T19:48:38.428Z] 19:48:38 INFO - PID 10724 | - authenticationSecret = undefined
[task 2020-04-03T19:48:38.428Z] 19:48:38 INFO - PID 10724 | - systemRecord = false
[task 2020-04-03T19:48:38.428Z] 19:48:38 INFO - PID 10724 | - appServerKey = undefined
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - recentMessageIDs = undefined
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - quota = Infinity
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - ctime = 0
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - channelID = 87902e90-c57e-4d18-8354-013f4a556559
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - version = 1
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | 3
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | queueRequest()
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | Object
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - channelID = 87902e90-c57e-4d18-8354-013f4a556559
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - messageType = unregister
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | - code = 200
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | enqueue()
[task 2020-04-03T19:48:38.429Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | delete: Removing record
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | 87902e90-c57e-4d18-8354-013f4a556559
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | wsSendMessage: Sending message
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | {"messageType":"hello","broadcasts":{},"use_webpush":true,"uaid":"7f0af1bb-7e1f-4fb8-8e4a-e8de434abde3"}
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | wsOnMessageAvailable()
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | {"messageType":"hello","status":200,"uaid":"7f0af1bb-7e1f-4fb8-8e4a-e8de434abde3","use_webpush":true}
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | handleHelloReply()
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | New _UAID
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | 7f0af1bb-7e1f-4fb8-8e4a-e8de434abde3
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | getAllUnexpired()
[task 2020-04-03T19:48:38.430Z] 19:48:38 INFO - PID 10724 | console.debug: PushDB:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | getAllByPushQuota()
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | onTimerFired()
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | timeOutRequests()
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | reconnect()
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | shutdownWS()
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | startBackoffTimer()
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | console.debug: PushServiceWebSocket:
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | startBackoffTimer: Retry in
[task 2020-04-03T19:48:38.431Z] 19:48:38 INFO - PID 10724 | 150
[task 2020-04-03T19:48:38.432Z] 19:48:38 INFO - PID 10724 | Try number
[task 2020-04-03T19:48:38.432Z] 19:48:38 INFO - PID 10724 | 1
[task 2020-04-03T19:48:38.432Z] 19:48:38 INFO - TEST-PASS | dom/push/test/xpcshell/test_unregister_invalid_json.js | test_unregister_invalid_json - [test_unregister_invalid_json : 1] Expected error for first invalid JSON response

Status: NEW → RESOLVED
Closed: 4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago4 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---
Status: REOPENED → RESOLVED
Closed: 4 years ago3 years ago
Resolution: --- → INCOMPLETE
Status: RESOLVED → REOPENED
Resolution: INCOMPLETE → ---

There are 39 total failures in the last 7 days on

[task 2021-09-24T21:18:22.607Z] 21:18:22     INFO -  TEST-START | dom/push/test/xpcshell/test_unregister_invalid_json.js
[task 2021-09-24T21:23:22.609Z] 21:23:22  WARNING -  TEST-UNEXPECTED-TIMEOUT | dom/push/test/xpcshell/test_unregister_invalid_json.js | Test timed out
[task 2021-09-24T21:23:22.610Z] 21:23:22     INFO -  TEST-INFO took 300003ms
[task 2021-09-24T21:23:22.610Z] 21:23:22     INFO -  >>>>>>>
[task 2021-09-24T21:23:22.610Z] 21:23:22     INFO -  PID 16096 | Couldn't convert chrome URL: chrome://branding/locale/brand.properties
[task 2021-09-24T21:23:22.611Z] 21:23:22     INFO -  PID 16096 | [Parent 16096, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-24T21:23:22.611Z] 21:23:22     INFO -  PID 16096 | [Parent 16096, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-24T21:23:22.611Z] 21:23:22     INFO -  PID 16096 | [Parent 16096, Main Thread] WARNING: Failed to get directory to cache.: file /builds/worker/checkouts/gecko/security/sandbox/win/src/sandboxbroker/sandboxBroker.cpp:119
[task 2021-09-24T21:23:22.612Z] 21:23:22     INFO -  PID 16096 | [Parent 16096, Main Thread] WARNING: Couldn't get the user appdata directory. Crash events may not be produced.: file /builds/worker/checkouts/gecko/toolkit/crashreporter/nsExceptionHandler.cpp:2940
[task 2021-09-24T21:23:22.612Z] 21:23:22     INFO -  (xpcshell/head.js) | test MAIN run_test pending (1)
[task 2021-09-24T21:23:22.612Z] 21:23:22     INFO -  (xpcshell/head.js) | test run_next_test 0 pending (2)
[task 2021-09-24T21:23:22.613Z] 21:23:22     INFO -  (xpcshell/head.js) | test MAIN run_test finished (2)
[task 2021-09-24T21:23:22.613Z] 21:23:22     INFO -  running event loop
[task 2021-09-24T21:23:22.613Z] 21:23:22     INFO -  PID 16096 | [Parent 16096, Main Thread] WARNING: Could not get the program name for a cubeb stream.: 'NS_SUCCEEDED(rv)', file /builds/worker/checkouts/gecko/dom/media/CubebUtils.cpp:385
[task 2021-09-24T21:23:22.614Z] 21:23:22     INFO -  dom/push/test/xpcshell/test_unregister_invalid_json.js | Starting test_unregister_invalid_json
[task 2021-09-24T21:23:22.614Z] 21:23:22     INFO -  (xpcshell/head.js) | test test_unregister_invalid_json pending (2)
[task 2021-09-24T21:23:22.614Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.614Z] 21:23:22     INFO -  PID 16096 |   PushDB()
[task 2021-09-24T21:23:22.615Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.615Z] 21:23:22     INFO -  PID 16096 |   put()
[task 2021-09-24T21:23:22.615Z] 21:23:22     INFO -  PID 16096 | Object
[task 2021-09-24T21:23:22.615Z] 21:23:22     INFO -  PID 16096 |     - channelID = 87902e90-c57e-4d18-8354-013f4a556559
[task 2021-09-24T21:23:22.616Z] 21:23:22     INFO -  PID 16096 |     - pushEndpoint = https://example.org/update/1
[task 2021-09-24T21:23:22.616Z] 21:23:22     INFO -  PID 16096 |     - scope = https://example.edu/page/1
[task 2021-09-24T21:23:22.616Z] 21:23:22     INFO -  PID 16096 |     - originAttributes =
[task 2021-09-24T21:23:22.616Z] 21:23:22     INFO -  PID 16096 |     - version = 1
[task 2021-09-24T21:23:22.616Z] 21:23:22     INFO -  PID 16096 |     - quota = Infinity
[task 2021-09-24T21:23:22.617Z] 21:23:22     INFO -  (xpcshell/head.js) | test run_next_test 0 finished (2)
[task 2021-09-24T21:23:22.617Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.617Z] 21:23:22     INFO -  PID 16096 |   put: Request successful. Updated record
[task 2021-09-24T21:23:22.617Z] 21:23:22     INFO -  PID 16096 |   87902e90-c57e-4d18-8354-013f4a556559
[task 2021-09-24T21:23:22.618Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.618Z] 21:23:22     INFO -  PID 16096 |   put()
[task 2021-09-24T21:23:22.618Z] 21:23:22     INFO -  PID 16096 | Object
[task 2021-09-24T21:23:22.618Z] 21:23:22     INFO -  PID 16096 |     - channelID = 057caa8f-9b99-47ff-891c-adad18ce603e
[task 2021-09-24T21:23:22.618Z] 21:23:22     INFO -  PID 16096 |     - pushEndpoint = https://example.com/update/2
[task 2021-09-24T21:23:22.619Z] 21:23:22     INFO -  PID 16096 |     - scope = https://example.net/page/1
[task 2021-09-24T21:23:22.619Z] 21:23:22     INFO -  PID 16096 |     - originAttributes =
[task 2021-09-24T21:23:22.619Z] 21:23:22     INFO -  PID 16096 |     - version = 1
[task 2021-09-24T21:23:22.619Z] 21:23:22     INFO -  PID 16096 |     - quota = Infinity
[task 2021-09-24T21:23:22.619Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.620Z] 21:23:22     INFO -  PID 16096 |   put: Request successful. Updated record
[task 2021-09-24T21:23:22.620Z] 21:23:22     INFO -  PID 16096 |   057caa8f-9b99-47ff-891c-adad18ce603e
[task 2021-09-24T21:23:22.620Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.620Z] 21:23:22     INFO -  PID 16096 |   init()
[task 2021-09-24T21:23:22.620Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.620Z] 21:23:22     INFO -  PID 16096 |   setState()
[task 2021-09-24T21:23:22.621Z] 21:23:22     INFO -  PID 16096 |   new state
[task 2021-09-24T21:23:22.621Z] 21:23:22     INFO -  PID 16096 |   2
[task 2021-09-24T21:23:22.621Z] 21:23:22     INFO -  PID 16096 |   old state
[task 2021-09-24T21:23:22.621Z] 21:23:22     INFO -  PID 16096 |   0
[task 2021-09-24T21:23:22.621Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.621Z] 21:23:22     INFO -  PID 16096 |   unregister()
[task 2021-09-24T21:23:22.622Z] 21:23:22     INFO -  PID 16096 | Object
[task 2021-09-24T21:23:22.622Z] 21:23:22     INFO -  PID 16096 |     - scope = https://example.edu/page/1
[task 2021-09-24T21:23:22.622Z] 21:23:22     INFO -  PID 16096 |     - originAttributes =
[task 2021-09-24T21:23:22.622Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.622Z] 21:23:22     INFO -  PID 16096 |   changeServerURL()
[task 2021-09-24T21:23:22.622Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.623Z] 21:23:22     INFO -  PID 16096 |   findService()
[task 2021-09-24T21:23:22.623Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.623Z] 21:23:22     INFO -  PID 16096 |   startService()
[task 2021-09-24T21:23:22.623Z] 21:23:22     INFO -  PID 16096 | console.debug: PushServiceWebSocket:
[task 2021-09-24T21:23:22.623Z] 21:23:22     INFO -  PID 16096 |   init()
[task 2021-09-24T21:23:22.624Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.624Z] 21:23:22     INFO -  PID 16096 |   startObservers()
[task 2021-09-24T21:23:22.624Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.624Z] 21:23:22     INFO -  PID 16096 |   dropExpiredRegistrations()
[task 2021-09-24T21:23:22.624Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.625Z] 21:23:22     INFO -  PID 16096 |   getAllExpired()
[task 2021-09-24T21:23:22.625Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.625Z] 21:23:22     INFO -  PID 16096 |   getAllByPushQuota()
[task 2021-09-24T21:23:22.625Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.625Z] 21:23:22     INFO -  PID 16096 |   changeStateConnectionEnabledEvent()
[task 2021-09-24T21:23:22.625Z] 21:23:22     INFO -  PID 16096 |   true
[task 2021-09-24T21:23:22.626Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.626Z] 21:23:22     INFO -  PID 16096 |   changeStateOfflineEvent()
[task 2021-09-24T21:23:22.626Z] 21:23:22     INFO -  PID 16096 |   false
[task 2021-09-24T21:23:22.626Z] 21:23:22     INFO -  PID 16096 | console.debug: PushService:
[task 2021-09-24T21:23:22.626Z] 21:23:22     INFO -  PID 16096 |   setState()
[task 2021-09-24T21:23:22.627Z] 21:23:22     INFO -  PID 16096 |   new state
[task 2021-09-24T21:23:22.627Z] 21:23:22     INFO -  PID 16096 |   5
[task 2021-09-24T21:23:22.627Z] 21:23:22     INFO -  PID 16096 |   old state
[task 2021-09-24T21:23:22.627Z] 21:23:22     INFO -  PID 16096 |   2
[task 2021-09-24T21:23:22.627Z] 21:23:22     INFO -  PID 16096 | console.debug: PushServiceWebSocket:
[task 2021-09-24T21:23:22.628Z] 21:23:22     INFO -  PID 16096 |   connect()
[task 2021-09-24T21:23:22.628Z] 21:23:22     INFO -  PID 16096 | Object
[task 2021-09-24T21:23:22.628Z] 21:23:22     INFO -  PID 16096 |   - prototype Object
[task 2021-09-24T21:23:22.628Z] 21:23:22     INFO -  PID 16096 | console.debug: PushServiceWebSocket:
[task 2021-09-24T21:23:22.628Z] 21:23:22     INFO -  PID 16096 |   beginWSSetup()
[task 2021-09-24T21:23:22.629Z] 21:23:22     INFO -  PID 16096 | console.debug: PushServiceWebSocket:
[task 2021-09-24T21:23:22.629Z] 21:23:22     INFO -  PID 16096 |   beginWSSetup: Connecting to
[task 2021-09-24T21:23:22.629Z] 21:23:22     INFO -  PID 16096 |   wss://push.example.org/
[task 2021-09-24T21:23:22.629Z] 21:23:22     INFO -  PID 16096 | console.debug: PushDB:
[task 2021-09-24T21:23:22.629Z] 21:23:22     INFO -  PID 16096 |   getByIdentifiers()
[task 2021-09-24T21:23:22.630Z] 21:23:22     INFO -  PID 16096 | Object

Jens, can you please take a look?

Flags: needinfo?(jstutte)

Update:

There have been 34 failures within the last 7 days:

  • 2 failures on linux1804-64-tsan-qr opt
  • 11 failures on Windows 10 x86 2004 WebRender debug
  • 18 failures on Windows 10 x64 2004 WebRender debug/opt
  • 1 failure on Windows 10 x64 2004 WebRender Shippable opt
  • 2 failures on Windows 10 x64 CCov WebRender opt

Recent failure: https://treeherder.mozilla.org/logviewer?job_id=353742030&repo=mozilla-central&lineNumber=6179

Assignee: nobody → abutkovits
Attachment #9244724 - Attachment description: Bug 1627379 - disable test_unregister_invalid_json.js on Win_QR_opt&debug for frequent failures. r=#intermittent-reviewers → Bug 1627379 - disable test_unregister_invalid_json.js on Win_QR for frequent failures. r=#intermittent-reviewers
Pushed by abutkovits@mozilla.com:
https://hg.mozilla.org/integration/autoland/rev/e2a3ccc89098
disable test_unregister_invalid_json.js on Win_QR for frequent failures. r=intermittent-reviewers,MasterWayZ DONTBUILD
Keywords: leave-open
Whiteboard: [stockwell needswork:owner] → [stockwell disabled]
Assignee: abutkovits → nobody
Status: REOPENED → RESOLVED
Closed: 3 years ago2 years ago
Resolution: --- → DUPLICATE
Flags: needinfo?(jstutte)
You need to log in before you can comment on or make changes to this bug.

Attachment

General

Created:
Updated:
Size: