1284128 - Intermittent browser/components/sessionstore/test/browser_backup_recovery.js | Test timed out -

Reporter

Description

•

8 years ago

treeherder

Filed by: philringnalda@gmail.com

https://treeherder.mozilla.org/logviewer.html#?job_id=174643&repo=autoland

http://archive.mozilla.org/pub/firefox/tinderbox-builds/autoland-win32-debug/1467378563/autoland_win7_ix-debug_test-mochitest-browser-chrome-3-bm127-tests1-windows-build4.txt.gz

Mike Conley (:mconley) (:⚙️)

Updated

•

8 years ago

Priority: -- → P3

Comment hidden (Intermittent Failures Robot)

1 failures in 814 pushes (0.001 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows7-32-vm: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1284128&startday=2017-06-12&endday=2017-06-18&tree=all

Comment hidden (Intermittent Failures Robot)

1 failures in 718 pushes (0.001 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* autoland: 1

Platform breakdown:
* windows8-64: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1284128&startday=2017-06-26&endday=2017-07-02&tree=all

Comment hidden (Intermittent Failures Robot)

1 failures in 656 pushes (0.002 failures/push) were associated with this bug in the last 7 days.   

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux32: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1284128&startday=2017-07-03&endday=2017-07-09&tree=all

Firefox Bug Husbandry Bot

Updated

•

7 years ago

Status: NEW → RESOLVED

Closed: 7 years ago

Keywords: bulk-close-intermittents

Resolution: --- → INCOMPLETE

Andreea Pavel [:apavel]

Comment 4

•

6 years ago

Recent failure log: https://treeherder.mozilla.org/logviewer.html#?job_id=155580785&repo=mozilla-inbound&lineNumber=3715

[task 2018-01-11T10:29:55.375Z] 10:29:55     INFO - TEST-START | browser/components/sessionstore/test/browser_backup_recovery.js
[task 2018-01-11T10:29:58.308Z] 10:29:58     INFO - GECKO(2484) | console.error:
[task 2018-01-11T10:29:58.309Z] 10:29:58     INFO - GECKO(2484) |   Corrupt session file (invalid JSON found)
[task 2018-01-11T10:29:58.311Z] 10:29:58     INFO - GECKO(2484) |   Message: SyntaxError: JSON.parse: unexpected character at line 1 column 1 of the JSON data
[task 2018-01-11T10:29:58.312Z] 10:29:58     INFO - GECKO(2484) |   Stack:
[task 2018-01-11T10:29:58.313Z] 10:29:58     INFO - GECKO(2484) |     _readInternal@resource:///modules/sessionstore/SessionFile.jsm:243:22
[task 2018-01-11T10:29:58.315Z] 10:29:58     INFO - GECKO(2484) | async*read@resource:///modules/sessionstore/SessionFile.jsm:291:40
[task 2018-01-11T10:29:58.317Z] 10:29:58     INFO - GECKO(2484) | async*read@resource:///modules/sessionstore/SessionFile.jsm:69:12
[task 2018-01-11T10:29:58.318Z] 10:29:58     INFO - GECKO(2484) | test_recovery@chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_backup_recovery.js:126:13
[task 2018-01-11T10:29:58.319Z] 10:29:58     INFO - GECKO(2484) | Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1065:21
[task 2018-01-11T10:29:58.321Z] 10:29:58     INFO - GECKO(2484) | TaskImpl_run@resource://gre/modules/Task.jsm:331:42
[task 2018-01-11T10:29:58.322Z] 10:29:58     INFO - GECKO(2484) | promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-01-11T10:29:58.323Z] 10:29:58     INFO - GECKO(2484) | TaskImpl_run@resource://gre/modules/Task.jsm:339:15
[task 2018-01-11T10:29:58.325Z] 10:29:58     INFO - GECKO(2484) | promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-01-11T10:29:58.326Z] 10:29:58     INFO - GECKO(2484) | TaskImpl_run@resource://gre/modules/Task.jsm:339:15
[task 2018-01-11T10:29:58.327Z] 10:29:58     INFO - GECKO(2484) | TaskImpl@resource://gre/modules/Task.jsm:280:3
[task 2018-01-11T10:29:58.329Z] 10:29:58     INFO - GECKO(2484) | asyncFunction@resource://gre/modules/Task.jsm:252:14
[task 2018-01-11T10:29:58.330Z] 10:29:58     INFO - GECKO(2484) | Task_spawn@resource://gre/modules/Task.jsm:166:12
[task 2018-01-11T10:29:58.331Z] 10:29:58     INFO - GECKO(2484) | Tester_execTest@chrome://mochikit/content/browser-test.js:1056:9
[task 2018-01-11T10:29:58.333Z] 10:29:58     INFO - GECKO(2484) | Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:956:9
[task 2018-01-11T10:29:58.335Z] 10:29:58     INFO - GECKO(2484) | SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2018-01-11T10:29:58.336Z] 10:29:58     INFO - GECKO(2484) |   _readInternal@resource:///modules/sessionstore/SessionFile.jsm:243:22
[task 2018-01-11T10:29:58.338Z] 10:29:58     INFO - GECKO(2484) | async*read@resource:///modules/sessionstore/SessionFile.jsm:291:40
[task 2018-01-11T10:29:58.339Z] 10:29:58     INFO - GECKO(2484) | async*read@resource:///modules/sessionstore/SessionFile.jsm:69:12
[task 2018-01-11T10:29:58.341Z] 10:29:58     INFO - GECKO(2484) | test_recovery@chrome://mochitests/content/browser/browser/components/sessionstore/test/browser_backup_recovery.js:126:13
[task 2018-01-11T10:29:58.342Z] 10:29:58     INFO - GECKO(2484) | Async*Tester_execTest/<@chrome://mochikit/content/browser-test.js:1065:21
[task 2018-01-11T10:29:58.343Z] 10:29:58     INFO - GECKO(2484) | TaskImpl_run@resource://gre/modules/Task.jsm:331:42
[task 2018-01-11T10:29:58.344Z] 10:29:58     INFO - GECKO(2484) | promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-01-11T10:29:58.345Z] 10:29:58     INFO - GECKO(2484) | TaskImpl_run@resource://gre/modules/Task.jsm:339:15
[task 2018-01-11T10:29:58.347Z] 10:29:58     INFO - GECKO(2484) | promise callback*TaskImpl_handleResultValue@resource://gre/modules/Task.jsm:408:7
[task 2018-01-11T10:29:58.348Z] 10:29:58     INFO - GECKO(2484) | TaskImpl_run@resource://gre/modules/Task.jsm:339:15
[task 2018-01-11T10:29:58.352Z] 10:29:58     INFO - GECKO(2484) | TaskImpl@resource://gre/modules/Task.jsm:280:3
[task 2018-01-11T10:29:58.352Z] 10:29:58     INFO - GECKO(2484) | asyncFunction@resource://gre/modules/Task.jsm:252:14
[task 2018-01-11T10:29:58.353Z] 10:29:58     INFO - GECKO(2484) | Task_spawn@resource://gre/modules/Task.jsm:166:12
[task 2018-01-11T10:29:58.353Z] 10:29:58     INFO - GECKO(2484) | Tester_execTest@chrome://mochikit/content/browser-test.js:1056:9
[task 2018-01-11T10:29:58.353Z] 10:29:58     INFO - GECKO(2484) | Tester.prototype.nextTest</<@chrome://mochikit/content/browser-test.js:956:9
[task 2018-01-11T10:29:58.354Z] 10:29:58     INFO - GECKO(2484) | SimpleTest.waitForFocus/waitForFocusInner/focusedOrLoaded/<@chrome://mochikit/content/tests/SimpleTest/SimpleTest.js:795:59
[task 2018-01-11T10:29:58.878Z] 10:29:58     INFO - GECKO(2484) | console.error:
[task 2018-01-11T10:29:58.879Z] 10:29:58     INFO - GECKO(2484) |   Could not read session file
[task 2018-01-11T10:29:58.880Z] 10:29:58     INFO - GECKO(2484) |   Message: Unix error 13 during operation open on file /tmp/tmpxE1_in.mozrunner/sessionstore-backups/recovery.jsonlz4 (Permission denied)
[task 2018-01-11T10:29:59.446Z] 10:29:59     INFO - GECKO(2484) | ###!!! [Parent][MessageChannel] Error: (msgtype=0x150084,name=PBrowser::Msg_Destroy) Channel error: cannot send/recv
[task 2018-01-11T10:30:40.395Z] 10:30:40     INFO - TEST-INFO | started process screentopng
[task 2018-01-11T10:30:40.902Z] 10:30:40     INFO - TEST-INFO | screentopng: exit 0
[task 2018-01-11T10:30:40.904Z] 10:30:40     INFO - Buffered messages logged at 10:29:55
[task 2018-01-11T10:30:40.905Z] 10:30:40     INFO - Entering test bound init
[task 2018-01-11T10:30:40.906Z] 10:30:40     INFO - Leaving test bound init
[task 2018-01-11T10:30:40.908Z] 10:30:40     INFO - Entering test bound test_creation
[task 2018-01-11T10:30:40.909Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After wipe clean sessionstore file doesn't exist - 
[task 2018-01-11T10:30:40.910Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After wipe recovery sessionstore file doesn't exist - 
[task 2018-01-11T10:30:40.912Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After wipe recoveryBackup sessionstore file doesn't exist - 
[task 2018-01-11T10:30:40.913Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After wipe cleanBackup sessionstore file doesn't exist - 
[task 2018-01-11T10:30:40.915Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After wipe, old backup doesn't exist - 
[task 2018-01-11T10:30:40.918Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After wipe, old upgrade backup doesn't exist - 
[task 2018-01-11T10:30:40.921Z] 10:30:40     INFO - Testing situation after a single write
[task 2018-01-11T10:30:40.923Z] 10:30:40     INFO - Buffered messages logged at 10:29:56
[task 2018-01-11T10:30:40.924Z] 10:30:40     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 174}]
[task 2018-01-11T10:30:40.926Z] 10:30:40     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 174}]
[task 2018-01-11T10:30:40.929Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After write, recovery sessionstore file exists again - 
[task 2018-01-11T10:30:40.930Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After write, recoveryBackup sessionstore doesn't exist - 
[task 2018-01-11T10:30:40.932Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovery sessionstore file contains the required tab - 
[task 2018-01-11T10:30:40.936Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After first write, clean shutdown sessionstore doesn't exist, since we haven't shutdown yet - 
[task 2018-01-11T10:30:40.937Z] 10:30:40     INFO - Testing situation after a second write
[task 2018-01-11T10:30:40.939Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After second write, recovery sessionstore file still exists - 
[task 2018-01-11T10:30:40.939Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovery sessionstore file contains the latest url - 
[task 2018-01-11T10:30:40.940Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After write, recoveryBackup sessionstore now exists - 
[task 2018-01-11T10:30:40.941Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovery backup doesn't contain the latest url - 
[task 2018-01-11T10:30:40.943Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovery backup contains the original url - 
[task 2018-01-11T10:30:40.945Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After first write, clean shutdown sessinstore doesn't exist, since we haven't shutdown yet - 
[task 2018-01-11T10:30:40.946Z] 10:30:40     INFO - Reinitialize, ensure that we haven't leaked sensitive files
[task 2018-01-11T10:30:40.948Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After second write, clean shutdown sessonstore doesn't exist, since we haven't shutdown yet - 
[task 2018-01-11T10:30:40.950Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After second write, clean shutdwn sessionstore doesn't exist, since we haven't shutdown yet - 
[task 2018-01-11T10:30:40.952Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After second write, clean sutdown sessionstore doesn't exist, since we haven't shutdown yet - 
[task 2018-01-11T10:30:40.953Z] 10:30:40     INFO - Leaving test bound test_creation
[task 2018-01-11T10:30:40.954Z] 10:30:40     INFO - Entering test bound test_recovery
[task 2018-01-11T10:30:40.958Z] 10:30:40     INFO - Attempting to recover from the recovery file
[task 2018-01-11T10:30:40.959Z] 10:30:40     INFO - Buffered messages logged at 10:29:57
[task 2018-01-11T10:30:40.960Z] 10:30:40     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 174}]
[task 2018-01-11T10:30:40.960Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovered the correct source from the recovery file - 
[task 2018-01-11T10:30:40.962Z] 10:30:40     INFO - Corrupting recovery file, attempting to recover from recovery backup
[task 2018-01-11T10:30:40.963Z] 10:30:40     INFO - Buffered messages logged at 10:29:58
[task 2018-01-11T10:30:40.964Z] 10:30:40     INFO - Console message: [JavaScript Warning: "Use of nsIFile in content process is deprecated." {file: "resource://gre/modules/FileUtils.jsm" line: 174}]
[task 2018-01-11T10:30:40.967Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovered the correct source from the recovery file - 
[task 2018-01-11T10:30:40.968Z] 10:30:40     INFO - Leaving test bound test_recovery
[task 2018-01-11T10:30:40.970Z] 10:30:40     INFO - Entering test bound test_recovery_inaccessible
[task 2018-01-11T10:30:40.971Z] 10:30:40     INFO - Making recovery file inaccessible, attempting to recover from recovery backup
[task 2018-01-11T10:30:40.972Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | Recovered the correct source from the recovery file - 
[task 2018-01-11T10:30:40.973Z] 10:30:40     INFO - Leaving test bound test_recovery_inaccessible
[task 2018-01-11T10:30:40.978Z] 10:30:40     INFO - Entering test bound test_clean
[task 2018-01-11T10:30:40.979Z] 10:30:40     INFO - Buffered messages logged at 10:29:59
[task 2018-01-11T10:30:40.981Z] 10:30:40     INFO - TEST-PASS | browser/components/sessionstore/test/browser_backup_recovery.js | After first read/write, clean shutdown file has been moved to cleanBackup - 
[task 2018-01-11T10:30:40.982Z] 10:30:40     INFO - Leaving test bound test_clean
[task 2018-01-11T10:30:40.983Z] 10:30:40     INFO - Entering test bound test_version
[task 2018-01-11T10:30:40.985Z] 10:30:40     INFO - Preparing sessionstore
[task 2018-01-11T10:30:40.986Z] 10:30:40     INFO - Console message: [JavaScript Error: "remote browser crashed while on about:blank
[task 2018-01-11T10:30:40.987Z] 10:30:40     INFO - " {file: "chrome://mochikit/content/mochitest-e10s-utils.js" line: 8}]
[task 2018-01-11T10:30:40.988Z] 10:30:40     INFO - e10s_init/<@chrome://mochikit/content/mochitest-e10s-utils.js:8:5
[task 2018-01-11T10:30:40.988Z] 10:30:40     INFO - EventListener.handleEvent*EventTargetInterposition.methods.addEventListener@resource://gre/modules/RemoteAddonsParent.jsm:673:5
[task 2018-01-11T10:30:40.989Z] 10:30:40     INFO - interposeProperty/desc.value@jar:file:///builds/worker/workspace/build/application/firefox/omni.ja!/components/multiprocessShims.js:157:52
[task 2018-01-11T10:30:40.990Z] 10:30:40     INFO - e10s_init@chrome://mochikit/content/mochitest-e10s-utils.js:6:3
[task 2018-01-11T10:30:40.991Z] 10:30:40     INFO - testInit@chrome://mochikit/content/browser-test.js:102:5
[task 2018-01-11T10:30:40.992Z] 10:30:40     INFO - setTimeout handler*@chrome://mochikit/content/browser-test.js:26:3
[task 2018-01-11T10:30:40.993Z] 10:30:40     INFO - 
[task 2018-01-11T10:30:40.994Z] 10:30:40     INFO - Buffered messages finished
[task 2018-01-11T10:30:40.995Z] 10:30:40     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_backup_recovery.js | Test timed out - 
[task 2018-01-11T10:30:40.997Z] 10:30:40     INFO - GECKO(2484) | MEMORY STAT | vsize 2694MB | residentFast 358MB | heapAllocated 108MB
[task 2018-01-11T10:30:40.999Z] 10:30:40     INFO - TEST-OK | browser/components/sessionstore/test/browser_backup_recovery.js | took 45036ms
[task 2018-01-11T10:30:41.007Z] 10:30:41     INFO - Not taking screenshot here: see the one that was previously logged
[task 2018-01-11T10:30:41.007Z] 10:30:41     INFO - TEST-UNEXPECTED-FAIL | browser/components/sessionstore/test/browser_backup_recovery.js | Found a tab after previous test timed out: about:blank - 
[task 2018-01-11T10:30:41.008Z] 10:30:41     INFO - checking window state

[task 2018-01-11T10:33:21.822Z] 10:33:21     INFO - mozcrash Downloading symbols from: https://queue.taskcluster.net/v1/task/H5wD3qAhQzS7DqRNWU129g/artifacts/public/build/target.crashreporter-symbols.zip
[task 2018-01-11T10:33:28.202Z] 10:33:28     INFO - mozcrash Copy/paste: /usr/local/bin/linux64-minidump_stackwalk /tmp/tmpxE1_in.mozrunner/minidumps/1a00c07e-c52f-6f43-cdb2-652ac97e9175.dmp /tmp/tmpeEcZ63
[task 2018-01-11T10:33:35.881Z] 10:33:35     INFO - mozcrash Saved minidump as /builds/worker/workspace/build/blobber_upload_dir/1a00c07e-c52f-6f43-cdb2-652ac97e9175.dmp
[task 2018-01-11T10:33:35.882Z] 10:33:35     INFO - mozcrash Saved app info as /builds/worker/workspace/build/blobber_upload_dir/1a00c07e-c52f-6f43-cdb2-652ac97e9175.extra
[task 2018-01-11T10:33:35.921Z] 10:33:35     INFO - PROCESS-CRASH | Main app process exited normally | application crashed [@ nsTSubstring<char>::Finalize]
[task 2018-01-11T10:33:35.921Z] 10:33:35     INFO - Crash dump filename: /tmp/tmpxE1_in.mozrunner/minidumps/1a00c07e-c52f-6f43-cdb2-652ac97e9175.dmp
[task 2018-01-11T10:33:35.922Z] 10:33:35     INFO - Operating system: Linux
[task 2018-01-11T10:33:35.923Z] 10:33:35     INFO -                   0.0.0 Linux 4.4.0-98-generic #121~14.04.1-Ubuntu SMP Wed Oct 11 11:54:55 UTC 2017 x86_64
[task 2018-01-11T10:33:35.923Z] 10:33:35     INFO - CPU: amd64
[task 2018-01-11T10:33:35.924Z] 10:33:35     INFO -      family 6 model 62 stepping 4
[task 2018-01-11T10:33:35.925Z] 10:33:35     INFO -      2 CPUs
[task 2018-01-11T10:33:35.925Z] 10:33:35     INFO - 
[task 2018-01-11T10:33:35.926Z] 10:33:35     INFO - GPU: UNKNOWN
[task 2018-01-11T10:33:35.926Z] 10:33:35     INFO - 
[task 2018-01-11T10:33:35.927Z] 10:33:35     INFO - Crash reason:  SIGSEGV
[task 2018-01-11T10:33:35.927Z] 10:33:35     INFO - Crash address: 0xc
[task 2018-01-11T10:33:35.928Z] 10:33:35     INFO - Process uptime: not available
[task 2018-01-11T10:33:35.928Z] 10:33:35     INFO - 
[task 2018-01-11T10:33:35.929Z] 10:33:35     INFO - Thread 0 (crashed)

Status: RESOLVED → REOPENED

Resolution: INCOMPLETE → ---

Comment hidden (Intermittent Failures Robot)

1 failures in 788 pushes (0.001 failures/push) were associated with this bug in the last 7 days.    

Repository breakdown:
* mozilla-inbound: 1

Platform breakdown:
* linux64-stylo-disabled: 1

For more details, see:
https://brasstacks.mozilla.com/orangefactor/?display=Bug&bugid=1284128&startday=2018-01-08&endday=2018-01-14&tree=all

Firefox Bug Husbandry Bot

Comment 6

•

6 years ago

https://wiki.mozilla.org/Bug_Triage#Intermittent_Test_Failure_Cleanup

Status: REOPENED → RESOLVED

Closed: 7 years ago → 6 years ago

Resolution: --- → INCOMPLETE

Bugzilla

Quick Search

Intermittent browser/components/sessionstore/test/browser_backup_recovery.js | Test timed out -

Categories

(Firefox :: Session Restore, defect, P3)

Tracking

()

People

(Reporter: intermittent-bug-filer, Unassigned)

References

Details

(Keywords: bulk-close-intermittents, intermittent-failure)

Crash Data

Security

(public)

User Story

Description

Updated

Comment 1

Comment 2

Comment 3

Updated

Comment 4

Comment 5

Comment 6