Page MenuHomePhabricator

Investigate Firefox failures on Kobiton
Closed, ResolvedPublic

Description

On Friday I turned on continuous test with Firefox on one phone. It worked for a while and then stopped:

Screen Shot 2020-10-19 at 8.35.20 AM.png (1×1 px, 128 KB)

Lets find out why.

Event Timeline

The last thing that happened on the phone was:

[2020-10-17 10:31:16] ERROR: Failed waiting on page https://en.m.wikipedia.org/wiki/Barack_Obama to finished loading, timed out after 300000 ms BrowserError: Running page complete check 
return (function(waitTime) {
    try { 
            var end = window.performance.timing.loadEventEnd;
            var start= window.performance.timing.navigationStart;
            return (end > 0) && (performance.now() > end - start + waitTime);
    } 
    catch(e) {
        return true;
    }
})(arguments[arguments.length - 1]);
 took too long
return (function(waitTime) {
    try { 
            var end = window.performance.timing.loadEventEnd;
            var start= window.performance.timing.navigationStart;
            return (end > 0) && (performance.now() > end - start + waitTime);
    } 
    catch(e) {
        return true;
    }
})(arguments[arguments.length - 1]);
 took too long
    at /usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:39:38
    at new Promise (<anonymous>)
    at timeout (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:38:5)
    at SeleniumRunner.wait (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:169:13)
    at SeleniumRunner.loadAndWait (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:259:20)
    at async Measure.start (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/command/measure.js:201:9)
    at async /usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/run.js:9:9
    at async Iteration.run (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/iteration.js:191:9)
    at async Engine.runByScript (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/index.js:215:20)
    at async Object.analyzeUrl (/usr/local/lib/node_modules/sitespeed.io/lib/plugins/browsertime/analyzer.js:124:21)
    at async Object.processMessage (/usr/local/lib/node_modules/sitespeed.io/lib/plugins/browsertime/index.js:134:24)
[2020-10-17 10:31:16] INFO: URL failed to load, trying 1 more time(s): Failed waiting on page https://en.m.wikipedia.org/wiki/Barack_Obama  to finished loading, timed out after 300000 ms 
[2020-10-17 10:36:17] ERROR: Failed waiting on page https://en.m.wikipedia.org/wiki/Barack_Obama to finished loading, timed out after 300000 ms BrowserError: Running page complete check 
return (function(waitTime) {
    try { 
            var end = window.performance.timing.loadEventEnd;
            var start= window.performance.timing.navigationStart;
            return (end > 0) && (performance.now() > end - start + waitTime);
    } 
    catch(e) {
        return true;
    }
})(arguments[arguments.length - 1]);
 took too long
return (function(waitTime) {
    try { 
            var end = window.performance.timing.loadEventEnd;
            var start= window.performance.timing.navigationStart;
            return (end > 0) && (performance.now() > end - start + waitTime);
    } 
    catch(e) {
        return true;
    }
})(arguments[arguments.length - 1]);
 took too long
    at /usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:39:38
    at new Promise (<anonymous>)
    at timeout (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:38:5)
    at SeleniumRunner.wait (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:169:13)
    at SeleniumRunner.loadAndWait (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:259:20)
    at async Measure.start (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/command/measure.js:201:9)
    at async /usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/run.js:9:9
    at async Iteration.run (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/iteration.js:191:9)
    at async Engine.runByScript (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/engine/index.js:215:20)
    at async Object.analyzeUrl (/usr/local/lib/node_modules/sitespeed.io/lib/plugins/browsertime/analyzer.js:124:21)
    at async Object.processMessage (/usr/local/lib/node_modules/sitespeed.io/lib/plugins/browsertime/index.js:134:24)
[2020-10-17 10:36:17] INFO: URL failed to load, trying 0 more time(s): Failed waiting on page https://en.m.wikipedia.org/wiki/Barack_Obama  to finished loading, timed out after 300000 ms 
[2020-10-17 10:36:17] ERROR: Could not load URL https://en.m.wikipedia.org/wiki/Barack_Obama UrlLoadError: Failed waiting on page https://en.m.wikipedia.org/wiki/Barack_Obama  to finished loading, timed out after 300000 ms 
    at SeleniumRunner.wait (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:186:13)
[2020-10-17 10:36:17] ERROR: UrlLoadError: Failed to load https://en.m.wikipedia.org/wiki/Barack_Obama
    at SeleniumRunner.loadAndWait (/usr/local/lib/node_modules/sitespeed.io/node_modules/browsertime/lib/core/seleniumRunner.js:306:17)
Oct 18 00:30:04 Kobitons-Mac-mini-156 newsyslog[22220]: logfile turned over

It is strange that nothing happened after that until the log was rotated. I can see that the start script is still alive:

/bin/bash ./loop.sh ZY322HX8RC-Moto-G5

But no call to Firefox. I've restarted the phone but I should probably should have looked in the logs on the phone. Let me restart the tests too.

Hmm I was wrong, restarting the phone got the test to start running again (without restarting the tests), but I couldn't see anything that was stuck in the logs or using ps. Strange.

Investigations happened in T263224