builder: fx-team_ubuntu64_hw_test-other slave: talos-linux64-ix-043 starttime: 1446455372.58 results: success (0) buildid: 20151102000831 builduid: 6ba3099163464737b29fc67280b93594 revision: 83ee1cb04baaedfa97074a651ce34c58973ff5e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.582258) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.582688) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.582979) ========= bash -c pwd in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'pwd'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006369 basedir: '/builds/slave/test' ========= master_lag: 0.06 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.653071) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.653907) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.825986) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.829294) ========= rm -rf properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'properties'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004558 ========= master_lag: 0.08 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.913366) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.913645) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.913999) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:32.914283) ========= bash -c 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'wget -Oarchiver_client.py --no-check-certificate --tries=10 --waitretry=3 https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-11-02 01:09:32-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 226M=0s 2015-11-02 01:09:33 (226 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.446185 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.434445) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.434764) ========= rm -rf scripts in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017588 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.497693) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:33.498025) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 83ee1cb04baaedfa97074a651ce34c58973ff5e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 83ee1cb04baaedfa97074a651ce34c58973ff5e4 --destination scripts --debug'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-11-02 01:09:33,506 truncating revision to first 12 chars 2015-11-02 01:09:33,506 Setting DEBUG logging. 2015-11-02 01:09:33,506 attempt 1/10 2015-11-02 01:09:33,506 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/83ee1cb04baa?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-11-02 01:09:33,745 unpacking tar archive at: fx-team-83ee1cb04baa/testing/mozharness/ program finished with exit code 0 elapsedTime=0.560449 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.075004) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.075312) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.085498) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.085759) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-11-02 01:09:34.086137) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 5 mins, 49 secs) (at 2015-11-02 01:09:34.086432) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'other', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team-Non-PGO'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 01:09:34 INFO - MultiFileLogger online at 20151102 01:09:34 in /builds/slave/test 01:09:34 INFO - Run as scripts/scripts/talos_script.py --suite other --add-option --webServer,localhost --branch-name Fx-Team-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team-Non-PGO 01:09:34 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:09:34 INFO - {'append_to_log': False, 01:09:34 INFO - 'base_work_dir': '/builds/slave/test', 01:09:34 INFO - 'blob_upload_branch': 'Fx-Team-Non-PGO', 01:09:34 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:09:34 INFO - 'branch': 'Fx-Team-Non-PGO', 01:09:34 INFO - 'buildbot_json_path': 'buildprops.json', 01:09:34 INFO - 'config_files': ('talos/linux_config.py',), 01:09:34 INFO - 'default_actions': ('clobber', 01:09:34 INFO - 'read-buildbot-config', 01:09:34 INFO - 'download-and-extract', 01:09:34 INFO - 'populate-webroot', 01:09:34 INFO - 'create-virtualenv', 01:09:34 INFO - 'install', 01:09:34 INFO - 'run-tests'), 01:09:34 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:09:34 INFO - 'download_minidump_stackwalk': True, 01:09:34 INFO - 'download_symbols': 'ondemand', 01:09:34 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:09:34 INFO - 'tooltool.py': '/tools/tooltool.py', 01:09:34 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:09:34 INFO - '/tools/misc-python/virtualenv.py')}, 01:09:34 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:09:34 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:09:34 INFO - 'installer_path': 'installer.exe', 01:09:34 INFO - 'log_level': 'info', 01:09:34 INFO - 'log_name': 'talos', 01:09:34 INFO - 'log_to_console': True, 01:09:34 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 01:09:34 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 01:09:34 INFO - 'opt_config_files': (), 01:09:34 INFO - 'pip_index': False, 01:09:34 INFO - 'sps_profile': False, 01:09:34 INFO - 'sps_profile_interval': 0, 01:09:34 INFO - 'suite': 'other', 01:09:34 INFO - 'system_bits': '64', 01:09:34 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:09:34 INFO - 'title': 'talos-linux64-ix-043', 01:09:34 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:09:34 INFO - 'use_talos_json': True, 01:09:34 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:09:34 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:09:34 INFO - 'work_dir': 'build'} 01:09:34 INFO - ##### 01:09:34 INFO - ##### Running clobber step. 01:09:34 INFO - ##### 01:09:34 INFO - Running pre-action listener: _resource_record_pre_action 01:09:34 INFO - Running main action method: clobber 01:09:34 INFO - rmtree: /builds/slave/test/build 01:09:34 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:09:34 INFO - Running post-action listener: _resource_record_post_action 01:09:34 INFO - ##### 01:09:34 INFO - ##### Running read-buildbot-config step. 01:09:34 INFO - ##### 01:09:34 INFO - Running pre-action listener: _resource_record_pre_action 01:09:34 INFO - Running main action method: read_buildbot_config 01:09:34 INFO - Using buildbot properties: 01:09:34 INFO - { 01:09:34 INFO - "properties": { 01:09:34 INFO - "buildnumber": 203, 01:09:34 INFO - "product": "firefox", 01:09:34 INFO - "script_repo_revision": "production", 01:09:34 INFO - "builddir": "fx-team_ubuntu64_hw_test-other", 01:09:34 INFO - "repository": "", 01:09:34 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team talos other", 01:09:34 INFO - "buildid": "20151102000831", 01:09:34 INFO - "slavename": "talos-linux64-ix-043", 01:09:34 INFO - "pgo_build": "False", 01:09:34 INFO - "basedir": "/builds/slave/test", 01:09:34 INFO - "project": "", 01:09:34 INFO - "platform": "ubuntu64_hw", 01:09:34 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 01:09:34 INFO - "slavebuilddir": "test", 01:09:34 INFO - "scheduler": "tests-fx-team-linux64-talos", 01:09:34 INFO - "branch": "fx-team", 01:09:34 INFO - "repo_path": "integration/fx-team", 01:09:34 INFO - "stage_platform": "linux64", 01:09:34 INFO - "builduid": "6ba3099163464737b29fc67280b93594", 01:09:34 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4" 01:09:34 INFO - }, 01:09:34 INFO - "sourcestamp": { 01:09:34 INFO - "repository": "", 01:09:34 INFO - "hasPatch": false, 01:09:34 INFO - "project": "", 01:09:34 INFO - "branch": "fx-team-linux64-talos", 01:09:34 INFO - "changes": [ 01:09:34 INFO - { 01:09:34 INFO - "category": null, 01:09:34 INFO - "files": [ 01:09:34 INFO - { 01:09:34 INFO - "url": null, 01:09:34 INFO - "name": "https://queue.taskcluster.net/v1/task/1RULjWpRSxqRDJibq0fsIQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 01:09:34 INFO - } 01:09:34 INFO - ], 01:09:34 INFO - "repository": "", 01:09:34 INFO - "rev": "dec44b4525eb56e6ef4d423f57a6193ddf099206", 01:09:34 INFO - "who": "cbook@mozilla.com", 01:09:34 INFO - "when": 1446455320, 01:09:34 INFO - "number": 6607837, 01:09:34 INFO - "comments": "Bug 1213888 - Block LayerView descendants from interfering with focus. r=kats\n\nUsing a hardware keyboard makes Android leave \"touch mode\", and when\nleaving touch mode from a ViewGroup that prefers descendant\nfocusability, which is what a ScrollView is, Android will shift the\nfocus to the next available View, and cause our LayerView to lose focus.\nThe fix is to set the descendant focusability to blocking.", 01:09:34 INFO - "project": "", 01:09:34 INFO - "at": "Mon 02 Nov 2015 01:08:40", 01:09:34 INFO - "branch": "fx-team-linux64-talos", 01:09:34 INFO - "revlink": "", 01:09:34 INFO - "properties": [ 01:09:34 INFO - [ 01:09:34 INFO - "buildid", 01:09:34 INFO - "20151102000411", 01:09:34 INFO - "Change" 01:09:34 INFO - ], 01:09:34 INFO - [ 01:09:34 INFO - "builduid", 01:09:34 INFO - "795443fafe4e45b9affa943e6cd3651b", 01:09:34 INFO - "Change" 01:09:34 INFO - ], 01:09:34 INFO - [ 01:09:34 INFO - "pgo_build", 01:09:34 INFO - "False", 01:09:34 INFO - "Change" 01:09:34 INFO - ] 01:09:34 INFO - ], 01:09:34 INFO - "revision": "dec44b4525eb56e6ef4d423f57a6193ddf099206" 01:09:34 INFO - }, 01:09:34 INFO - { 01:09:34 INFO - "category": null, 01:09:34 INFO - "files": [ 01:09:34 INFO - { 01:09:34 INFO - "url": null, 01:09:34 INFO - "name": "https://queue.taskcluster.net/v1/task/16GXNDrqS1Wk0l89ocgmgQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 01:09:34 INFO - } 01:09:34 INFO - ], 01:09:34 INFO - "repository": "", 01:09:34 INFO - "rev": "46dc0b2f7dce24eb1a5ff6f3341368332a26fcb2", 01:09:34 INFO - "who": "cbook@mozilla.com", 01:09:34 INFO - "when": 1446455324, 01:09:34 INFO - "number": 6607839, 01:09:34 INFO - "comments": "Bug 1205843 - Part 2: Disable maintenance service on WinXP < SP3 - r=rstrong\n\nAttempt to get around the fact that XP SP2 (and below) systems won't be able to\nhandle Authenticode signatues with SHA-2 digests by disabling the maintenance\nservice, the only thing on our update path that uses Authenticode, on systems\nrunning WinXP below SP3.\n\nThis patch both prevents the service from being included in new installations,\nand prevents the service from being used where it is already installed.", 01:09:34 INFO - "project": "", 01:09:34 INFO - "at": "Mon 02 Nov 2015 01:08:44", 01:09:34 INFO - "branch": "fx-team-linux64-talos", 01:09:34 INFO - "revlink": "", 01:09:34 INFO - "properties": [ 01:09:34 INFO - [ 01:09:34 INFO - "buildid", 01:09:34 INFO - "20151102000630", 01:09:34 INFO - "Change" 01:09:34 INFO - ], 01:09:34 INFO - [ 01:09:34 INFO - "builduid", 01:09:34 INFO - "07e935540f0a43198a540945d6001681", 01:09:34 INFO - "Change" 01:09:34 INFO - ], 01:09:34 INFO - [ 01:09:34 INFO - "pgo_build", 01:09:34 INFO - "False", 01:09:34 INFO - "Change" 01:09:34 INFO - ] 01:09:34 INFO - ], 01:09:34 INFO - "revision": "46dc0b2f7dce24eb1a5ff6f3341368332a26fcb2" 01:09:34 INFO - }, 01:09:34 INFO - { 01:09:34 INFO - "category": null, 01:09:34 INFO - "files": [ 01:09:34 INFO - { 01:09:34 INFO - "url": null, 01:09:34 INFO - "name": "https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 01:09:34 INFO - } 01:09:34 INFO - ], 01:09:34 INFO - "repository": "", 01:09:34 INFO - "rev": "83ee1cb04baaedfa97074a651ce34c58973ff5e4", 01:09:34 INFO - "who": "cbook@mozilla.com", 01:09:34 INFO - "when": 1446455326, 01:09:34 INFO - "number": 6607842, 01:09:34 INFO - "comments": "Bug 1220407 - include sys/resource.h for struct rusage and getrusage() on all unices, not linux only. r=yoric", 01:09:34 INFO - "project": "", 01:09:34 INFO - "at": "Mon 02 Nov 2015 01:08:46", 01:09:34 INFO - "branch": "fx-team-linux64-talos", 01:09:34 INFO - "revlink": "", 01:09:34 INFO - "properties": [ 01:09:34 INFO - [ 01:09:34 INFO - "buildid", 01:09:34 INFO - "20151102000831", 01:09:34 INFO - "Change" 01:09:34 INFO - ], 01:09:34 INFO - [ 01:09:34 INFO - "builduid", 01:09:34 INFO - "6ba3099163464737b29fc67280b93594", 01:09:34 INFO - "Change" 01:09:34 INFO - ], 01:09:34 INFO - [ 01:09:34 INFO - "pgo_build", 01:09:34 INFO - "False", 01:09:34 INFO - "Change" 01:09:34 INFO - ] 01:09:34 INFO - ], 01:09:34 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4" 01:09:34 INFO - } 01:09:34 INFO - ], 01:09:34 INFO - "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4" 01:09:34 INFO - } 01:09:34 INFO - } 01:09:34 INFO - Found installer url https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 01:09:34 INFO - Running post-action listener: _resource_record_post_action 01:09:34 INFO - ##### 01:09:34 INFO - ##### Running download-and-extract step. 01:09:34 INFO - ##### 01:09:34 INFO - Running pre-action listener: _resource_record_pre_action 01:09:34 INFO - Running main action method: download_and_extract 01:09:34 INFO - mkdir: /builds/slave/test/build/tests 01:09:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:09:34 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 01:09:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json 01:09:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json 01:09:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 01:09:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 01:09:39 INFO - Downloaded 1302 bytes. 01:09:39 INFO - Reading from file /builds/slave/test/build/test_packages.json 01:09:39 INFO - Using the following test package requirements: 01:09:39 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:09:39 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 01:09:39 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'jsshell-linux-x86_64.zip'], 01:09:39 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 01:09:39 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:09:39 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 01:09:39 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 01:09:39 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 01:09:39 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 01:09:39 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 01:09:39 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 01:09:39 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 01:09:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:09:39 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:09:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:09:40 INFO - Downloaded 21245053 bytes. 01:09:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 01:09:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:40 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:09:41 INFO - Return code: 0 01:09:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 01:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:09:41 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 01:09:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 01:09:55 INFO - Downloaded 21245053 bytes. 01:09:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] in /builds/slave/test/build/tests 01:09:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 01:09:55 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] with output_timeout 1760 01:09:57 INFO - Return code: 0 01:09:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:09:57 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 01:09:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:09:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:09:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:09:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 01:09:58 INFO - Downloaded 11131307 bytes. 01:09:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] in /builds/slave/test/build/tests 01:09:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 01:09:58 INFO - Calling ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'] with output_timeout 1760 01:09:58 INFO - Return code: 0 01:09:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:09:58 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 01:09:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:09:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:09:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:09:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 01:10:00 INFO - Downloaded 55535362 bytes. 01:10:00 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:10:00 INFO - mkdir: /builds/slave/test/properties 01:10:00 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:10:00 INFO - Writing to file /builds/slave/test/properties/build_url 01:10:00 INFO - Contents: 01:10:00 INFO - build_url:https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 01:10:00 INFO - Running post-action listener: _resource_record_post_action 01:10:00 INFO - Running post-action listener: set_extra_try_arguments 01:10:00 INFO - ##### 01:10:00 INFO - ##### Running populate-webroot step. 01:10:00 INFO - ##### 01:10:00 INFO - Running pre-action listener: _resource_record_pre_action 01:10:00 INFO - Running main action method: populate_webroot 01:10:00 INFO - Guessing talos json url... 01:10:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:00 INFO - https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt matches https://queue.taskcluster.net 01:10:00 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt 01:10:00 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt 01:10:00 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt to /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.txt 01:10:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.txt', 'file_name': '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.txt'}, attempt #1 01:10:01 INFO - Downloaded 103 bytes. 01:10:01 INFO - Reading from file /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.txt 01:10:01 INFO - Contents: 01:10:01 INFO - 20151102000831 01:10:01 INFO - https://hg.mozilla.org/integration/fx-team/rev/83ee1cb04baaedfa97074a651ce34c58973ff5e4 01:10:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:01 INFO - trying https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json 01:10:01 INFO - Downloading https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json to /builds/slave/test/build/talos.json 01:10:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://hg.mozilla.org/integration/fx-team/raw-file/83ee1cb04b/testing/talos/talos.json', 'file_name': '/builds/slave/test/build/talos.json'}, attempt #1 01:10:01 INFO - Downloaded 4264 bytes. 01:10:01 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:10:01 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:10:01 INFO - 'remote-trobocheck2': {'talos_options': ['--fennecIDs', 01:10:01 INFO - '../fennec_ids.txt'], 01:10:01 INFO - 'tests': ['tcheck2']}, 01:10:01 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:10:01 INFO - '--tppagecycles', 01:10:01 INFO - '7'], 01:10:01 INFO - 'tests': ['tsvgm']}}, 01:10:01 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 01:10:01 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:10:01 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:10:01 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 01:10:01 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:10:01 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:10:01 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'tests': ['damp', 'tps']}, 01:10:01 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['damp', 'tps']}, 01:10:01 INFO - 'g3': {'tests': ['dromaeo_dom']}, 01:10:01 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:10:01 INFO - 'other': {'tests': ['a11yr', 01:10:01 INFO - 'ts_paint', 01:10:01 INFO - 'tpaint', 01:10:01 INFO - 'sessionrestore', 01:10:01 INFO - 'sessionrestore_no_auto_restore']}, 01:10:01 INFO - 'other-e10s': {'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['a11yr', 01:10:01 INFO - 'ts_paint', 01:10:01 INFO - 'tpaint', 01:10:01 INFO - 'sessionrestore', 01:10:01 INFO - 'sessionrestore_no_auto_restore']}, 01:10:01 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['a11yr', 01:10:01 INFO - 'ts_paint', 01:10:01 INFO - 'tpaint', 01:10:01 INFO - 'sessionrestore', 01:10:01 INFO - 'sessionrestore_no_auto_restore']}, 01:10:01 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['a11yr', 01:10:01 INFO - 'ts_paint', 01:10:01 INFO - 'tpaint', 01:10:01 INFO - 'sessionrestore', 01:10:01 INFO - 'sessionrestore_no_auto_restore']}, 01:10:01 INFO - 'other_l64': {'tests': ['a11yr', 01:10:01 INFO - 'ts_paint', 01:10:01 INFO - 'tpaint', 01:10:01 INFO - 'sessionrestore', 01:10:01 INFO - 'sessionrestore_no_auto_restore']}, 01:10:01 INFO - 'other_nol64': {'tests': ['a11yr', 01:10:01 INFO - 'ts_paint', 01:10:01 INFO - 'tpaint', 01:10:01 INFO - 'sessionrestore', 01:10:01 INFO - 'sessionrestore_no_auto_restore']}, 01:10:01 INFO - 'svgr': {'tests': ['tsvgx', 01:10:01 INFO - 'tsvgr_opacity', 01:10:01 INFO - 'tart', 01:10:01 INFO - 'tscrollx', 01:10:01 INFO - 'cart']}, 01:10:01 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['tsvgx', 01:10:01 INFO - 'tsvgr_opacity', 01:10:01 INFO - 'tart', 01:10:01 INFO - 'tscrollx', 01:10:01 INFO - 'cart']}, 01:10:01 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'tests': ['tp5o']}, 01:10:01 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'talos_options': ['--e10s'], 01:10:01 INFO - 'tests': ['tp5o']}, 01:10:01 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'talos_options': ['--xperf_path', 01:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:10:01 INFO - 'tests': ['tp5n']}, 01:10:01 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 01:10:01 INFO - 'talos_options': ['--e10s', 01:10:01 INFO - '--xperf_path', 01:10:01 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"', 01:10:01 INFO - 'C:/slave/talos-data/talos/xperf.config'], 01:10:01 INFO - 'tests': ['tp5n']}}, 01:10:01 INFO - 'talos.zip': {'path': '', 01:10:01 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:10:01 INFO - Running post-action listener: _resource_record_post_action 01:10:01 INFO - ##### 01:10:01 INFO - ##### Running create-virtualenv step. 01:10:01 INFO - ##### 01:10:01 INFO - Running pre-action listener: _resource_record_pre_action 01:10:01 INFO - Running main action method: create_virtualenv 01:10:01 INFO - Creating virtualenv /builds/slave/test/build/venv 01:10:01 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 01:10:01 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:10:01 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:10:01 INFO - Using real prefix '/usr' 01:10:01 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:10:02 INFO - Installing distribute.............................................................................................................................................................................................done. 01:10:04 INFO - Installing pip.................done. 01:10:04 INFO - Return code: 0 01:10:04 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 01:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 01:10:04 INFO - Using env: {'DISPLAY': ':0', 01:10:04 INFO - 'HOME': '/home/cltbld', 01:10:04 INFO - 'LANG': 'en_US.UTF-8', 01:10:04 INFO - 'LANGUAGE': 'en_US:en', 01:10:04 INFO - 'LOGNAME': 'cltbld', 01:10:04 INFO - 'MAIL': '/var/mail/cltbld', 01:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:04 INFO - 'MOZ_NO_REMOTE': '1', 01:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:04 INFO - 'NO_EM_RESTART': '1', 01:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:04 INFO - 'PWD': '/builds/slave/test', 01:10:04 INFO - 'SHELL': '/bin/bash', 01:10:04 INFO - 'SHLVL': '1', 01:10:04 INFO - 'TERM': 'linux', 01:10:04 INFO - 'TMOUT': '86400', 01:10:04 INFO - 'USER': 'cltbld', 01:10:04 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:04 INFO - '_': '/tools/buildbot/bin/python'} 01:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:04 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 01:10:04 INFO - Cleaning up... 01:10:04 INFO - Return code: 0 01:10:04 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 01:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:04 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 01:10:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 01:10:04 INFO - Using env: {'DISPLAY': ':0', 01:10:04 INFO - 'HOME': '/home/cltbld', 01:10:04 INFO - 'LANG': 'en_US.UTF-8', 01:10:04 INFO - 'LANGUAGE': 'en_US:en', 01:10:04 INFO - 'LOGNAME': 'cltbld', 01:10:04 INFO - 'MAIL': '/var/mail/cltbld', 01:10:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:04 INFO - 'MOZ_NO_REMOTE': '1', 01:10:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:04 INFO - 'NO_EM_RESTART': '1', 01:10:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:04 INFO - 'PWD': '/builds/slave/test', 01:10:04 INFO - 'SHELL': '/bin/bash', 01:10:04 INFO - 'SHLVL': '1', 01:10:04 INFO - 'TERM': 'linux', 01:10:04 INFO - 'TMOUT': '86400', 01:10:04 INFO - 'USER': 'cltbld', 01:10:04 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:04 INFO - '_': '/tools/buildbot/bin/python'} 01:10:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:04 INFO - Downloading/unpacking psutil>=0.7.1 01:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:06 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 01:10:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 01:10:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:10:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:10:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:10:06 INFO - Installing collected packages: psutil 01:10:06 INFO - Running setup.py install for psutil 01:10:06 INFO - building 'psutil._psutil_linux' extension 01:10:06 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 01:10:07 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 01:10:07 INFO - building 'psutil._psutil_posix' extension 01:10:07 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 01:10:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 01:10:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:10:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:10:08 INFO - Successfully installed psutil 01:10:08 INFO - Cleaning up... 01:10:08 INFO - Return code: 0 01:10:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 01:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 01:10:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 01:10:08 INFO - Using env: {'DISPLAY': ':0', 01:10:08 INFO - 'HOME': '/home/cltbld', 01:10:08 INFO - 'LANG': 'en_US.UTF-8', 01:10:08 INFO - 'LANGUAGE': 'en_US:en', 01:10:08 INFO - 'LOGNAME': 'cltbld', 01:10:08 INFO - 'MAIL': '/var/mail/cltbld', 01:10:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:08 INFO - 'MOZ_NO_REMOTE': '1', 01:10:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:08 INFO - 'NO_EM_RESTART': '1', 01:10:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:08 INFO - 'PWD': '/builds/slave/test', 01:10:08 INFO - 'SHELL': '/bin/bash', 01:10:08 INFO - 'SHLVL': '1', 01:10:08 INFO - 'TERM': 'linux', 01:10:08 INFO - 'TMOUT': '86400', 01:10:08 INFO - 'USER': 'cltbld', 01:10:08 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:08 INFO - '_': '/tools/buildbot/bin/python'} 01:10:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 01:10:10 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 01:10:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:10:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 01:10:10 INFO - Installing collected packages: mozsystemmonitor 01:10:10 INFO - Running setup.py install for mozsystemmonitor 01:10:10 INFO - Successfully installed mozsystemmonitor 01:10:10 INFO - Cleaning up... 01:10:10 INFO - Return code: 0 01:10:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:10:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 01:10:10 INFO - Using env: {'DISPLAY': ':0', 01:10:10 INFO - 'HOME': '/home/cltbld', 01:10:10 INFO - 'LANG': 'en_US.UTF-8', 01:10:10 INFO - 'LANGUAGE': 'en_US:en', 01:10:10 INFO - 'LOGNAME': 'cltbld', 01:10:10 INFO - 'MAIL': '/var/mail/cltbld', 01:10:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:10 INFO - 'MOZ_NO_REMOTE': '1', 01:10:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:10 INFO - 'NO_EM_RESTART': '1', 01:10:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:10 INFO - 'PWD': '/builds/slave/test', 01:10:10 INFO - 'SHELL': '/bin/bash', 01:10:10 INFO - 'SHLVL': '1', 01:10:10 INFO - 'TERM': 'linux', 01:10:10 INFO - 'TMOUT': '86400', 01:10:10 INFO - 'USER': 'cltbld', 01:10:10 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:10 INFO - '_': '/tools/buildbot/bin/python'} 01:10:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:10 INFO - Downloading/unpacking blobuploader==1.2.4 01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - Downloading blobuploader-1.2.4.tar.gz 01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 01:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:10:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 01:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:10:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:12 INFO - Downloading docopt-0.6.1.tar.gz 01:10:12 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 01:10:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:10:12 INFO - Installing collected packages: blobuploader, requests, docopt 01:10:12 INFO - Running setup.py install for blobuploader 01:10:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:10:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:10:12 INFO - Running setup.py install for requests 01:10:13 INFO - Running setup.py install for docopt 01:10:13 INFO - Successfully installed blobuploader requests docopt 01:10:13 INFO - Cleaning up... 01:10:13 INFO - Return code: 0 01:10:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:10:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:10:13 INFO - Using env: {'DISPLAY': ':0', 01:10:13 INFO - 'HOME': '/home/cltbld', 01:10:13 INFO - 'LANG': 'en_US.UTF-8', 01:10:13 INFO - 'LANGUAGE': 'en_US:en', 01:10:13 INFO - 'LOGNAME': 'cltbld', 01:10:13 INFO - 'MAIL': '/var/mail/cltbld', 01:10:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:13 INFO - 'MOZ_NO_REMOTE': '1', 01:10:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:13 INFO - 'NO_EM_RESTART': '1', 01:10:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:13 INFO - 'PWD': '/builds/slave/test', 01:10:13 INFO - 'SHELL': '/bin/bash', 01:10:13 INFO - 'SHLVL': '1', 01:10:13 INFO - 'TERM': 'linux', 01:10:13 INFO - 'TMOUT': '86400', 01:10:13 INFO - 'USER': 'cltbld', 01:10:13 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:13 INFO - '_': '/tools/buildbot/bin/python'} 01:10:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:10:13 INFO - Running setup.py (path:/tmp/pip-VCCbX2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:10:13 INFO - Running setup.py (path:/tmp/pip-jQU5Y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:10:13 INFO - Running setup.py (path:/tmp/pip-uIIzji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:10:13 INFO - Running setup.py (path:/tmp/pip-F8JHM1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:10:13 INFO - Running setup.py (path:/tmp/pip-0T_opC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:10:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:10:13 INFO - Running setup.py (path:/tmp/pip-W7BafH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:10:14 INFO - Running setup.py (path:/tmp/pip-QJ34yR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:10:14 INFO - Running setup.py (path:/tmp/pip-dP8dAt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:10:14 INFO - Running setup.py (path:/tmp/pip-IBAZMD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:10:14 INFO - Running setup.py (path:/tmp/pip-IkogsF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:10:14 INFO - Running setup.py (path:/tmp/pip-4Bd5Ks-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:10:14 INFO - Running setup.py (path:/tmp/pip-Q2RTZx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:10:14 INFO - Running setup.py (path:/tmp/pip-YcXYk7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:10:14 INFO - Running setup.py (path:/tmp/pip-WazSRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:10:14 INFO - Running setup.py (path:/tmp/pip-pup9e_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:10:14 INFO - Running setup.py (path:/tmp/pip-TzM8tW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:10:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:10:14 INFO - Running setup.py (path:/tmp/pip-xiWpsj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:10:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:10:14 INFO - Running setup.py install for manifestparser 01:10:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:10:14 INFO - Running setup.py install for mozcrash 01:10:15 INFO - Running setup.py install for mozdebug 01:10:15 INFO - Running setup.py install for mozdevice 01:10:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Running setup.py install for mozfile 01:10:15 INFO - Running setup.py install for mozhttpd 01:10:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Running setup.py install for mozinfo 01:10:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Running setup.py install for mozInstall 01:10:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Running setup.py install for mozleak 01:10:15 INFO - Running setup.py install for mozlog 01:10:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Running setup.py install for moznetwork 01:10:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:10:15 INFO - Running setup.py install for mozprocess 01:10:16 INFO - Running setup.py install for mozprofile 01:10:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:10:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:10:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:10:16 INFO - Running setup.py install for mozrunner 01:10:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:10:16 INFO - Running setup.py install for mozscreenshot 01:10:16 INFO - Running setup.py install for moztest 01:10:16 INFO - Running setup.py install for mozversion 01:10:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:10:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:10:16 INFO - Cleaning up... 01:10:16 INFO - Return code: 0 01:10:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:16 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:10:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:10:16 INFO - Using env: {'DISPLAY': ':0', 01:10:16 INFO - 'HOME': '/home/cltbld', 01:10:16 INFO - 'LANG': 'en_US.UTF-8', 01:10:16 INFO - 'LANGUAGE': 'en_US:en', 01:10:16 INFO - 'LOGNAME': 'cltbld', 01:10:16 INFO - 'MAIL': '/var/mail/cltbld', 01:10:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:16 INFO - 'MOZ_NO_REMOTE': '1', 01:10:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:16 INFO - 'NO_EM_RESTART': '1', 01:10:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:16 INFO - 'PWD': '/builds/slave/test', 01:10:16 INFO - 'SHELL': '/bin/bash', 01:10:16 INFO - 'SHLVL': '1', 01:10:16 INFO - 'TERM': 'linux', 01:10:16 INFO - 'TMOUT': '86400', 01:10:16 INFO - 'USER': 'cltbld', 01:10:16 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:16 INFO - '_': '/tools/buildbot/bin/python'} 01:10:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:10:16 INFO - Running setup.py (path:/tmp/pip-60diK2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:10:16 INFO - Running setup.py (path:/tmp/pip-M8A7Vq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.16 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:10:16 INFO - Running setup.py (path:/tmp/pip-qopLIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:10:16 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:10:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:10:17 INFO - Running setup.py (path:/tmp/pip-iZ0dMq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.47 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:10:17 INFO - Running setup.py (path:/tmp/pip-jZLm7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:10:17 INFO - Running setup.py (path:/tmp/pip-iNgDVc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:10:17 INFO - Running setup.py (path:/tmp/pip-MC2Yp8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:10:17 INFO - Running setup.py (path:/tmp/pip-WeIVO8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:10:17 INFO - Running setup.py (path:/tmp/pip-FnmImv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:10:17 INFO - Running setup.py (path:/tmp/pip-jgxhnA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.0 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:10:17 INFO - Running setup.py (path:/tmp/pip-lOqfXv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:10:17 INFO - Running setup.py (path:/tmp/pip-m5k91o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:10:17 INFO - Running setup.py (path:/tmp/pip-71rf2m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.27 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:10:17 INFO - Running setup.py (path:/tmp/pip-shikEd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:10:17 INFO - Running setup.py (path:/tmp/pip-jXl0q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:10:17 INFO - Running setup.py (path:/tmp/pip-9g5ZgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:10:17 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:10:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:10:17 INFO - Running setup.py (path:/tmp/pip-lUjtdx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 01:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.16->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:10:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.47->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:10:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:10:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:10:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:10:19 INFO - Downloading blessings-1.5.1.tar.gz 01:10:19 INFO - Storing download in cache at /builds/slave/test/build/venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblessings-1.5.1.tar.gz 01:10:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:10:19 INFO - Installing collected packages: blessings 01:10:19 INFO - Running setup.py install for blessings 01:10:19 INFO - Successfully installed blessings 01:10:19 INFO - Cleaning up... 01:10:19 INFO - Return code: 0 01:10:19 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:10:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:10:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:10:20 INFO - Reading from file tmpfile_stdout 01:10:20 INFO - Current package versions: 01:10:20 INFO - argparse == 1.2.1 01:10:20 INFO - blessings == 1.5.1 01:10:20 INFO - blobuploader == 1.2.4 01:10:20 INFO - docopt == 0.6.1 01:10:20 INFO - manifestparser == 1.1 01:10:20 INFO - mozInstall == 1.12 01:10:20 INFO - mozcrash == 0.16 01:10:20 INFO - mozdebug == 0.1 01:10:20 INFO - mozdevice == 0.47 01:10:20 INFO - mozfile == 1.2 01:10:20 INFO - mozhttpd == 0.7 01:10:20 INFO - mozinfo == 0.9 01:10:20 INFO - mozleak == 0.1 01:10:20 INFO - mozlog == 3.0 01:10:20 INFO - moznetwork == 0.27 01:10:20 INFO - mozprocess == 0.22 01:10:20 INFO - mozprofile == 0.27 01:10:20 INFO - mozrunner == 6.11 01:10:20 INFO - mozscreenshot == 0.1 01:10:20 INFO - mozsystemmonitor == 0.0 01:10:20 INFO - moztest == 0.7 01:10:20 INFO - mozversion == 1.4 01:10:20 INFO - psutil == 3.1.1 01:10:20 INFO - requests == 1.2.3 01:10:20 INFO - wsgiref == 0.1.2 01:10:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:10:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:10:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:10:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f102a670c70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15107b0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15dbf80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'LANG': 'en_US.UTF-8', 'TERM': 'linux', 'SHELL': '/bin/bash', 'MOZ_NO_REMOTE': '1', 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 01:10:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--download-cache', '/builds/slave/test/build/venv/cache', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --download-cache /builds/slave/test/build/venv/cache --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:10:20 INFO - Using env: {'DISPLAY': ':0', 01:10:20 INFO - 'HOME': '/home/cltbld', 01:10:20 INFO - 'LANG': 'en_US.UTF-8', 01:10:20 INFO - 'LANGUAGE': 'en_US:en', 01:10:20 INFO - 'LOGNAME': 'cltbld', 01:10:20 INFO - 'MAIL': '/var/mail/cltbld', 01:10:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:20 INFO - 'MOZ_NO_REMOTE': '1', 01:10:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:20 INFO - 'NO_EM_RESTART': '1', 01:10:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:20 INFO - 'PWD': '/builds/slave/test', 01:10:20 INFO - 'SHELL': '/bin/bash', 01:10:20 INFO - 'SHLVL': '1', 01:10:20 INFO - 'TERM': 'linux', 01:10:20 INFO - 'TMOUT': '86400', 01:10:20 INFO - 'USER': 'cltbld', 01:10:20 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:20 INFO - '_': '/tools/buildbot/bin/python'} 01:10:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 01:10:20 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.0->mozcrash>=0.15->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 01:10:20 INFO - Cleaning up... 01:10:20 INFO - Return code: 0 01:10:20 INFO - Running post-action listener: _resource_record_post_action 01:10:20 INFO - Running post-action listener: _start_resource_monitoring 01:10:20 INFO - Starting resource monitoring. 01:10:20 INFO - ##### 01:10:20 INFO - ##### Running install step. 01:10:20 INFO - ##### 01:10:20 INFO - Running pre-action listener: _resource_record_pre_action 01:10:20 INFO - Running main action method: install 01:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:10:20 INFO - Reading from file tmpfile_stdout 01:10:20 INFO - Detecting whether we're running mozinstall >=1.0... 01:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:10:20 INFO - Reading from file tmpfile_stdout 01:10:20 INFO - Output received: 01:10:20 INFO - Usage: mozinstall [options] installer 01:10:20 INFO - Options: 01:10:20 INFO - -h, --help show this help message and exit 01:10:20 INFO - -d DEST, --destination=DEST 01:10:20 INFO - Directory to install application into. [default: 01:10:20 INFO - "/builds/slave/test"] 01:10:20 INFO - --app=APP Application being installed. [default: firefox] 01:10:20 INFO - mkdir: /builds/slave/test/build/application 01:10:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 01:10:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 01:10:35 INFO - Reading from file tmpfile_stdout 01:10:35 INFO - Output received: 01:10:35 INFO - /builds/slave/test/build/application/firefox/firefox 01:10:35 INFO - Running post-action listener: _resource_record_post_action 01:10:35 INFO - ##### 01:10:35 INFO - ##### Running run-tests step. 01:10:35 INFO - ##### 01:10:35 INFO - Running pre-action listener: _resource_record_pre_action 01:10:35 INFO - Running main action method: run_tests 01:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:10:35 INFO - Python 2.7.3 01:10:35 INFO - Return code: 0 01:10:35 INFO - grabbing minidump binary from tooltool 01:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 01:10:35 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16729d0>, 'level': 'warning'}, {'substr': 'Traceback (most recent call last)', 'level': 'error'}, {'substr': 'SyntaxError: ', 'level': 'error'}, {'substr': 'TypeError: ', 'level': 'error'}, {'substr': 'NameError: ', 'level': 'error'}, {'substr': 'ZeroDivisionError: ', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16739e0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1673e70>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:10:35 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 01:10:35 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 01:10:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:10:35 INFO - Return code: 0 01:10:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 01:10:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:10:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:10:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 01:10:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:10:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'other', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost'] in /builds/slave/test/build 01:10:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Fx-Team-Non-PGO --suite other --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-043 --webServer localhost 01:10:35 INFO - Using env: {'DISPLAY': ':0', 01:10:35 INFO - 'HOME': '/home/cltbld', 01:10:35 INFO - 'LANG': 'en_US.UTF-8', 01:10:35 INFO - 'LANGUAGE': 'en_US:en', 01:10:35 INFO - 'LOGNAME': 'cltbld', 01:10:35 INFO - 'MAIL': '/var/mail/cltbld', 01:10:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:10:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 01:10:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:10:35 INFO - 'MOZ_NO_REMOTE': '1', 01:10:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:10:35 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 01:10:35 INFO - 'NO_EM_RESTART': '1', 01:10:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 01:10:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:10:35 INFO - 'PWD': '/builds/slave/test', 01:10:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:10:35 INFO - 'SHELL': '/bin/bash', 01:10:35 INFO - 'SHLVL': '1', 01:10:35 INFO - 'TERM': 'linux', 01:10:35 INFO - 'TMOUT': '86400', 01:10:35 INFO - 'USER': 'cltbld', 01:10:35 INFO - 'XDG_SESSION_COOKIE': 'ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588', 01:10:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:10:35 INFO - '_': '/tools/buildbot/bin/python'} 01:10:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Fx-Team-Non-PGO', '--suite', 'other', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-043', '--webServer', 'localhost'] with output_timeout 3600 01:10:35 INFO - mozversion INFO | application_buildid: 20151102000831 01:10:35 INFO - mozversion INFO | application_changeset: 83ee1cb04baaedfa97074a651ce34c58973ff5e4 01:10:35 INFO - mozversion INFO | application_display_name: Nightly 01:10:35 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:10:35 INFO - mozversion INFO | application_name: Firefox 01:10:35 INFO - mozversion INFO | application_remotingname: firefox 01:10:35 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/integration/fx-team 01:10:35 INFO - mozversion INFO | application_vendor: Mozilla 01:10:35 INFO - mozversion INFO | application_version: 45.0a1 01:10:35 INFO - mozversion INFO | platform_buildid: 20151102000831 01:10:35 INFO - mozversion INFO | platform_changeset: 83ee1cb04baaedfa97074a651ce34c58973ff5e4 01:10:35 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/integration/fx-team 01:10:35 INFO - mozversion INFO | platform_version: 45.0a1 01:10:35 INFO - 2015-11-02 01:10:35,680 DEBUG : using testdate: 1446455435 01:10:35 INFO - 2015-11-02 01:10:35,680 DEBUG : actual date: 1446455435 01:10:35 INFO - 2015-11-02 01:10:35,761 INFO : starting webserver on 'localhost:36796' 01:10:35 INFO - 2015-11-02 01:10:35,762 INFO : Starting test suite talos-linux64-ix-043 01:10:35 INFO - 2015-11-02 01:10:35,762 INFO : Starting test a11yr 01:10:35 INFO - 2015-11-02 01:10:35,763 DEBUG : operating with platform_type : linux_ 01:10:35 INFO - 2015-11-02 01:10:35,763 INFO : Initialising browser for a11yr test... 01:10:35 INFO - 2015-11-02 01:10:35,805 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGSzdt/profile http://localhost:36796/getInfo.html 01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:10:40 INFO - 2015-11-02 01:10:40,110 DEBUG : BROWSER_OUTPUT: __metrics 01:10:40 INFO - 2015-11-02 01:10:40,252 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:10:41 INFO - 2015-11-02 01:10:41,324 INFO : Browser initialized. 01:10:41 INFO - 2015-11-02 01:10:41,325 INFO : Running cycle 1/1 for a11yr test... 01:10:41 INFO - 2015-11-02 01:10:41,325 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiGSzdt/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/a11y/a11y.manifest.develop -tpchrome -tpmozafterpaint -tpnoisy -tpcycles 1 -tppagecycles 25 01:10:53 INFO - 2015-11-02 01:10:53,181 DEBUG : BROWSER_OUTPUT: RSS: Main: 140685312 01:10:53 INFO - 2015-11-02 01:10:53,181 DEBUG : BROWSER_OUTPUT: 01:10:54 INFO - 2015-11-02 01:10:54,606 DEBUG : BROWSER_OUTPUT: 01:10:54 INFO - 2015-11-02 01:10:54,606 DEBUG : BROWSER_OUTPUT: (firefox:3430): GLib-GObject-CRITICAL **: g_type_add_interface_static: assertion `g_type_parent (interface_type) == G_TYPE_INTERFACE' failed 01:10:54 INFO - 2015-11-02 01:10:54,622 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:10:55 INFO - 2015-11-02 01:10:55,236 DEBUG : BROWSER_OUTPUT: RSS: Main: 178176000 01:10:55 INFO - 2015-11-02 01:10:55,237 DEBUG : BROWSER_OUTPUT: 01:10:56 INFO - 2015-11-02 01:10:56,763 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:10:57 INFO - 2015-11-02 01:10:57,444 DEBUG : BROWSER_OUTPUT: RSS: Main: 181260288 01:10:57 INFO - 2015-11-02 01:10:57,444 DEBUG : BROWSER_OUTPUT: 01:10:58 INFO - 2015-11-02 01:10:58,970 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:10:59 INFO - 2015-11-02 01:10:59,658 DEBUG : BROWSER_OUTPUT: RSS: Main: 180834304 01:10:59 INFO - 2015-11-02 01:10:59,658 DEBUG : BROWSER_OUTPUT: 01:11:01 INFO - 2015-11-02 01:11:01,188 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:01 INFO - 2015-11-02 01:11:01,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 181960704 01:11:01 INFO - 2015-11-02 01:11:01,904 DEBUG : BROWSER_OUTPUT: 01:11:03 INFO - 2015-11-02 01:11:03,562 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:04 INFO - 2015-11-02 01:11:04,250 DEBUG : BROWSER_OUTPUT: RSS: Main: 180076544 01:11:04 INFO - 2015-11-02 01:11:04,250 DEBUG : BROWSER_OUTPUT: 01:11:05 INFO - 2015-11-02 01:11:05,836 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:06 INFO - 2015-11-02 01:11:06,538 DEBUG : BROWSER_OUTPUT: RSS: Main: 183234560 01:11:06 INFO - 2015-11-02 01:11:06,539 DEBUG : BROWSER_OUTPUT: 01:11:08 INFO - 2015-11-02 01:11:08,076 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:08 INFO - 2015-11-02 01:11:08,764 DEBUG : BROWSER_OUTPUT: RSS: Main: 179728384 01:11:08 INFO - 2015-11-02 01:11:08,764 DEBUG : BROWSER_OUTPUT: 01:11:10 INFO - 2015-11-02 01:11:10,382 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:11 INFO - 2015-11-02 01:11:11,086 DEBUG : BROWSER_OUTPUT: RSS: Main: 182169600 01:11:11 INFO - 2015-11-02 01:11:11,086 DEBUG : BROWSER_OUTPUT: 01:11:12 INFO - 2015-11-02 01:11:12,530 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:13 INFO - 2015-11-02 01:11:13,221 DEBUG : BROWSER_OUTPUT: RSS: Main: 182976512 01:11:13 INFO - 2015-11-02 01:11:13,221 DEBUG : BROWSER_OUTPUT: 01:11:14 INFO - 2015-11-02 01:11:14,654 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:15 INFO - 2015-11-02 01:11:15,368 DEBUG : BROWSER_OUTPUT: RSS: Main: 183128064 01:11:15 INFO - 2015-11-02 01:11:15,368 DEBUG : BROWSER_OUTPUT: 01:11:16 INFO - 2015-11-02 01:11:16,995 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:17 INFO - 2015-11-02 01:11:17,684 DEBUG : BROWSER_OUTPUT: RSS: Main: 181686272 01:11:17 INFO - 2015-11-02 01:11:17,684 DEBUG : BROWSER_OUTPUT: 01:11:19 INFO - 2015-11-02 01:11:19,194 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:19 INFO - 2015-11-02 01:11:19,210 DEBUG : BROWSER_OUTPUT: 01:11:19 INFO - 2015-11-02 01:11:19,210 DEBUG : BROWSER_OUTPUT: (firefox:3430): GLib-GObject-CRITICAL **: g_type_add_interface_static: assertion `g_type_parent (interface_type) == G_TYPE_INTERFACE' failed 01:11:19 INFO - 2015-11-02 01:11:19,893 DEBUG : BROWSER_OUTPUT: RSS: Main: 183017472 01:11:19 INFO - 2015-11-02 01:11:19,893 DEBUG : BROWSER_OUTPUT: 01:11:21 INFO - 2015-11-02 01:11:21,320 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:22 INFO - 2015-11-02 01:11:22,023 DEBUG : BROWSER_OUTPUT: RSS: Main: 183533568 01:11:22 INFO - 2015-11-02 01:11:22,023 DEBUG : BROWSER_OUTPUT: 01:11:23 INFO - 2015-11-02 01:11:23,549 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:24 INFO - 2015-11-02 01:11:24,243 DEBUG : BROWSER_OUTPUT: RSS: Main: 183877632 01:11:24 INFO - 2015-11-02 01:11:24,243 DEBUG : BROWSER_OUTPUT: 01:11:25 INFO - 2015-11-02 01:11:25,774 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:26 INFO - 2015-11-02 01:11:26,468 DEBUG : BROWSER_OUTPUT: RSS: Main: 180973568 01:11:26 INFO - 2015-11-02 01:11:26,468 DEBUG : BROWSER_OUTPUT: 01:11:27 INFO - 2015-11-02 01:11:27,982 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:28 INFO - 2015-11-02 01:11:28,676 DEBUG : BROWSER_OUTPUT: RSS: Main: 181501952 01:11:28 INFO - 2015-11-02 01:11:28,676 DEBUG : BROWSER_OUTPUT: 01:11:30 INFO - 2015-11-02 01:11:30,208 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:30 INFO - 2015-11-02 01:11:30,903 DEBUG : BROWSER_OUTPUT: RSS: Main: 180662272 01:11:30 INFO - 2015-11-02 01:11:30,903 DEBUG : BROWSER_OUTPUT: 01:11:32 INFO - 2015-11-02 01:11:32,333 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:33 INFO - 2015-11-02 01:11:33,035 DEBUG : BROWSER_OUTPUT: RSS: Main: 181043200 01:11:33 INFO - 2015-11-02 01:11:33,036 DEBUG : BROWSER_OUTPUT: 01:11:34 INFO - 2015-11-02 01:11:34,554 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:35 INFO - 2015-11-02 01:11:35,249 DEBUG : BROWSER_OUTPUT: RSS: Main: 181235712 01:11:35 INFO - 2015-11-02 01:11:35,249 DEBUG : BROWSER_OUTPUT: 01:11:36 INFO - 2015-11-02 01:11:36,775 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:37 INFO - 2015-11-02 01:11:37,462 DEBUG : BROWSER_OUTPUT: RSS: Main: 181948416 01:11:37 INFO - 2015-11-02 01:11:37,463 DEBUG : BROWSER_OUTPUT: 01:11:38 INFO - 2015-11-02 01:11:38,893 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:39 INFO - 2015-11-02 01:11:39,583 DEBUG : BROWSER_OUTPUT: RSS: Main: 181309440 01:11:39 INFO - 2015-11-02 01:11:39,583 DEBUG : BROWSER_OUTPUT: 01:11:41 INFO - 2015-11-02 01:11:41,209 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:41 INFO - 2015-11-02 01:11:41,912 DEBUG : BROWSER_OUTPUT: RSS: Main: 192036864 01:11:41 INFO - 2015-11-02 01:11:41,912 DEBUG : BROWSER_OUTPUT: 01:11:43 INFO - 2015-11-02 01:11:43,473 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:43 INFO - 2015-11-02 01:11:43,481 DEBUG : BROWSER_OUTPUT: 1446455503478 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 01:11:44 INFO - 2015-11-02 01:11:44,185 DEBUG : BROWSER_OUTPUT: RSS: Main: 191315968 01:11:44 INFO - 2015-11-02 01:11:44,185 DEBUG : BROWSER_OUTPUT: 01:11:45 INFO - 2015-11-02 01:11:45,812 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:46 INFO - 2015-11-02 01:11:46,523 DEBUG : BROWSER_OUTPUT: RSS: Main: 192851968 01:11:46 INFO - 2015-11-02 01:11:46,523 DEBUG : BROWSER_OUTPUT: 01:11:48 INFO - 2015-11-02 01:11:48,074 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html) 01:11:48 INFO - 2015-11-02 01:11:48,787 DEBUG : BROWSER_OUTPUT: RSS: Main: 194314240 01:11:48 INFO - 2015-11-02 01:11:48,787 DEBUG : BROWSER_OUTPUT: 01:11:49 INFO - 2015-11-02 01:11:49,982 DEBUG : BROWSER_OUTPUT: 01:11:49 INFO - 2015-11-02 01:11:49,983 DEBUG : BROWSER_OUTPUT: (firefox:3430): GLib-GObject-CRITICAL **: g_type_add_interface_static: assertion `g_type_parent (interface_type) == G_TYPE_INTERFACE' failed 01:11:50 INFO - 2015-11-02 01:11:50,004 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:50 INFO - 2015-11-02 01:11:50,586 DEBUG : BROWSER_OUTPUT: RSS: Main: 190353408 01:11:50 INFO - 2015-11-02 01:11:50,586 DEBUG : BROWSER_OUTPUT: 01:11:51 INFO - 2015-11-02 01:11:51,435 DEBUG : BROWSER_OUTPUT: Cycle 1(2): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:52 INFO - 2015-11-02 01:11:52,036 DEBUG : BROWSER_OUTPUT: RSS: Main: 188231680 01:11:52 INFO - 2015-11-02 01:11:52,036 DEBUG : BROWSER_OUTPUT: 01:11:52 INFO - 2015-11-02 01:11:52,878 DEBUG : BROWSER_OUTPUT: Cycle 1(3): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:53 INFO - 2015-11-02 01:11:53,460 DEBUG : BROWSER_OUTPUT: RSS: Main: 188796928 01:11:53 INFO - 2015-11-02 01:11:53,461 DEBUG : BROWSER_OUTPUT: 01:11:54 INFO - 2015-11-02 01:11:54,327 DEBUG : BROWSER_OUTPUT: Cycle 1(4): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:54 INFO - 2015-11-02 01:11:54,913 DEBUG : BROWSER_OUTPUT: RSS: Main: 182800384 01:11:54 INFO - 2015-11-02 01:11:54,913 DEBUG : BROWSER_OUTPUT: 01:11:55 INFO - 2015-11-02 01:11:55,783 DEBUG : BROWSER_OUTPUT: Cycle 1(5): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:56 INFO - 2015-11-02 01:11:56,372 DEBUG : BROWSER_OUTPUT: RSS: Main: 180277248 01:11:56 INFO - 2015-11-02 01:11:56,372 DEBUG : BROWSER_OUTPUT: 01:11:57 INFO - 2015-11-02 01:11:57,221 DEBUG : BROWSER_OUTPUT: Cycle 1(6): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:57 INFO - 2015-11-02 01:11:57,809 DEBUG : BROWSER_OUTPUT: RSS: Main: 180649984 01:11:57 INFO - 2015-11-02 01:11:57,809 DEBUG : BROWSER_OUTPUT: 01:11:58 INFO - 2015-11-02 01:11:58,631 DEBUG : BROWSER_OUTPUT: Cycle 1(7): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:11:59 INFO - 2015-11-02 01:11:59,217 DEBUG : BROWSER_OUTPUT: RSS: Main: 180039680 01:11:59 INFO - 2015-11-02 01:11:59,218 DEBUG : BROWSER_OUTPUT: 01:12:00 INFO - 2015-11-02 01:12:00,091 DEBUG : BROWSER_OUTPUT: Cycle 1(8): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:00 INFO - 2015-11-02 01:12:00,681 DEBUG : BROWSER_OUTPUT: RSS: Main: 180121600 01:12:00 INFO - 2015-11-02 01:12:00,682 DEBUG : BROWSER_OUTPUT: 01:12:01 INFO - 2015-11-02 01:12:01,531 DEBUG : BROWSER_OUTPUT: Cycle 1(9): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:02 INFO - 2015-11-02 01:12:02,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 178782208 01:12:02 INFO - 2015-11-02 01:12:02,118 DEBUG : BROWSER_OUTPUT: 01:12:02 INFO - 2015-11-02 01:12:02,971 DEBUG : BROWSER_OUTPUT: Cycle 1(10): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:03 INFO - 2015-11-02 01:12:03,560 DEBUG : BROWSER_OUTPUT: RSS: Main: 179142656 01:12:03 INFO - 2015-11-02 01:12:03,561 DEBUG : BROWSER_OUTPUT: 01:12:04 INFO - 2015-11-02 01:12:04,407 DEBUG : BROWSER_OUTPUT: Cycle 1(11): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:04 INFO - 2015-11-02 01:12:04,993 DEBUG : BROWSER_OUTPUT: RSS: Main: 180236288 01:12:04 INFO - 2015-11-02 01:12:04,993 DEBUG : BROWSER_OUTPUT: 01:12:05 INFO - 2015-11-02 01:12:05,854 DEBUG : BROWSER_OUTPUT: Cycle 1(12): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:06 INFO - 2015-11-02 01:12:06,449 DEBUG : BROWSER_OUTPUT: RSS: Main: 180813824 01:12:06 INFO - 2015-11-02 01:12:06,449 DEBUG : BROWSER_OUTPUT: 01:12:07 INFO - 2015-11-02 01:12:07,310 DEBUG : BROWSER_OUTPUT: Cycle 1(13): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:07 INFO - 2015-11-02 01:12:07,913 DEBUG : BROWSER_OUTPUT: RSS: Main: 178397184 01:12:07 INFO - 2015-11-02 01:12:07,913 DEBUG : BROWSER_OUTPUT: 01:12:08 INFO - 2015-11-02 01:12:08,776 DEBUG : BROWSER_OUTPUT: Cycle 1(14): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:09 INFO - 2015-11-02 01:12:09,362 DEBUG : BROWSER_OUTPUT: RSS: Main: 178286592 01:12:09 INFO - 2015-11-02 01:12:09,362 DEBUG : BROWSER_OUTPUT: 01:12:10 INFO - 2015-11-02 01:12:10,228 DEBUG : BROWSER_OUTPUT: Cycle 1(15): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:10 INFO - 2015-11-02 01:12:10,811 DEBUG : BROWSER_OUTPUT: RSS: Main: 176422912 01:12:10 INFO - 2015-11-02 01:12:10,811 DEBUG : BROWSER_OUTPUT: 01:12:11 INFO - 2015-11-02 01:12:11,684 DEBUG : BROWSER_OUTPUT: Cycle 1(16): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:12 INFO - 2015-11-02 01:12:12,271 DEBUG : BROWSER_OUTPUT: RSS: Main: 177999872 01:12:12 INFO - 2015-11-02 01:12:12,271 DEBUG : BROWSER_OUTPUT: 01:12:13 INFO - 2015-11-02 01:12:13,295 DEBUG : BROWSER_OUTPUT: Cycle 1(17): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:13 INFO - 2015-11-02 01:12:13,882 DEBUG : BROWSER_OUTPUT: RSS: Main: 178372608 01:12:13 INFO - 2015-11-02 01:12:13,882 DEBUG : BROWSER_OUTPUT: 01:12:14 INFO - 2015-11-02 01:12:14,756 DEBUG : BROWSER_OUTPUT: Cycle 1(18): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:15 INFO - 2015-11-02 01:12:15,342 DEBUG : BROWSER_OUTPUT: RSS: Main: 179458048 01:12:15 INFO - 2015-11-02 01:12:15,342 DEBUG : BROWSER_OUTPUT: 01:12:16 INFO - 2015-11-02 01:12:16,184 DEBUG : BROWSER_OUTPUT: Cycle 1(19): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:16 INFO - 2015-11-02 01:12:16,771 DEBUG : BROWSER_OUTPUT: RSS: Main: 177201152 01:12:16 INFO - 2015-11-02 01:12:16,771 DEBUG : BROWSER_OUTPUT: 01:12:17 INFO - 2015-11-02 01:12:17,638 DEBUG : BROWSER_OUTPUT: Cycle 1(20): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:18 INFO - 2015-11-02 01:12:18,233 DEBUG : BROWSER_OUTPUT: RSS: Main: 177549312 01:12:18 INFO - 2015-11-02 01:12:18,233 DEBUG : BROWSER_OUTPUT: 01:12:19 INFO - 2015-11-02 01:12:19,087 DEBUG : BROWSER_OUTPUT: Cycle 1(21): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:19 INFO - 2015-11-02 01:12:19,677 DEBUG : BROWSER_OUTPUT: RSS: Main: 177242112 01:12:19 INFO - 2015-11-02 01:12:19,677 DEBUG : BROWSER_OUTPUT: 01:12:20 INFO - 2015-11-02 01:12:20,530 DEBUG : BROWSER_OUTPUT: Cycle 1(22): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:21 INFO - 2015-11-02 01:12:21,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 178618368 01:12:21 INFO - 2015-11-02 01:12:21,117 DEBUG : BROWSER_OUTPUT: 01:12:21 INFO - 2015-11-02 01:12:21,990 DEBUG : BROWSER_OUTPUT: Cycle 1(23): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:22 INFO - 2015-11-02 01:12:22,572 DEBUG : BROWSER_OUTPUT: RSS: Main: 177692672 01:12:22 INFO - 2015-11-02 01:12:22,572 DEBUG : BROWSER_OUTPUT: 01:12:23 INFO - 2015-11-02 01:12:23,434 DEBUG : BROWSER_OUTPUT: Cycle 1(24): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:24 INFO - 2015-11-02 01:12:24,037 DEBUG : BROWSER_OUTPUT: RSS: Main: 179589120 01:12:24 INFO - 2015-11-02 01:12:24,037 DEBUG : BROWSER_OUTPUT: 01:12:24 INFO - 2015-11-02 01:12:24,899 DEBUG : BROWSER_OUTPUT: Cycle 1(25): loaded file:///builds/slave/test/build/tests/talos/talos/tests/a11y/tablemutation.html (next: file:///builds/slave/test/build/tests/talos/talos/tests/a11y/dhtml.html) 01:12:25 INFO - 2015-11-02 01:12:25,149 DEBUG : BROWSER_OUTPUT: RSS: Main: 180686848 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: __start_tp_report 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: |0;dhtml.html;1385;1435;1448;1447;1545;1525;1446;1523;1354;1355;1531;1441;1352;1443;1449;1446;1452;1352;1446;1447;1351;1532;1474;1537;1462 01:12:25 INFO - 2015-11-02 01:12:25,150 DEBUG : BROWSER_OUTPUT: |1;tablemutation.html;330;321;321;324;322;322;324;326;322;323;321;323;322;323;323;324;493;324;320;324;322;320;324;324;322 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __end_tp_report 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __start_cc_report 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,6834 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __end_cc_report 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455545148__endTimestamp 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: 01:12:25 INFO - 2015-11-02 01:12:25,151 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 01:12:25 INFO - 2015-11-02 01:12:25,152 DEBUG : BROWSER_OUTPUT: Number of tests: 2 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: [#0] dhtml.html Cycles:25 Average:1447.12 Median:1447.00 stddev:62.33 (4.3%) stddev-sans-first:62.29 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: Values: 1385.0 1435.0 1448.0 1447.0 1545.0 1525.0 1446.0 1523.0 1354.0 1355.0 1531.0 1441.0 1352.0 1443.0 1449.0 1446.0 1452.0 1352.0 1446.0 1447.0 1351.0 1532.0 1474.0 1537.0 1462.0 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: [#1] tablemutation.html Cycles:25 Average:329.76 Median:323.00 stddev:34.07 (10.5%) stddev-sans-first:34.80 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: Values: 330.0 321.0 321.0 324.0 322.0 322.0 324.0 326.0 322.0 323.0 321.0 323.0 322.0 323.0 323.0 324.0 493.0 324.0 320.0 324.0 322.0 320.0 324.0 324.0 322.0 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 01:12:25 INFO - 2015-11-02 01:12:25,153 DEBUG : BROWSER_OUTPUT: 01:12:25 INFO - 2015-11-02 01:12:25,849 INFO : Browser exited with error code: 0 01:12:25 INFO - 2015-11-02 01:12:25,867 INFO : Completed test a11yr (00:01:50) 01:12:25 INFO - 2015-11-02 01:12:25,867 INFO : Starting test ts_paint 01:12:25 INFO - 2015-11-02 01:12:25,867 DEBUG : operating with platform_type : linux_ 01:12:25 INFO - 2015-11-02 01:12:25,868 INFO : Initialising browser for ts_paint test... 01:12:25 INFO - 2015-11-02 01:12:25,874 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/getInfo.html 01:12:29 INFO - 2015-11-02 01:12:29,774 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:12:29 INFO - 2015-11-02 01:12:29,775 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:12:29 INFO - 2015-11-02 01:12:29,775 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:12:29 INFO - 2015-11-02 01:12:29,775 DEBUG : BROWSER_OUTPUT: __metrics 01:12:29 INFO - 2015-11-02 01:12:29,919 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:12:31 INFO - 2015-11-02 01:12:31,093 INFO : Browser initialized. 01:12:31 INFO - 2015-11-02 01:12:31,093 INFO : Running cycle 1/20 for ts_paint test... 01:12:31 INFO - 2015-11-02 01:12:31,093 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:32 INFO - 2015-11-02 01:12:32,714 DEBUG : BROWSER_OUTPUT: __start_report1614__end_report 01:12:32 INFO - 2015-11-02 01:12:32,715 DEBUG : BROWSER_OUTPUT: 01:12:32 INFO - 2015-11-02 01:12:32,735 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455552731__endTimestamp 01:12:33 INFO - 2015-11-02 01:12:33,584 INFO : Browser exited with error code: 0 01:12:33 INFO - 2015-11-02 01:12:33,585 INFO : Running cycle 2/20 for ts_paint test... 01:12:33 INFO - 2015-11-02 01:12:33,585 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:34 INFO - 2015-11-02 01:12:34,716 DEBUG : BROWSER_OUTPUT: __start_report1123__end_report 01:12:34 INFO - 2015-11-02 01:12:34,716 DEBUG : BROWSER_OUTPUT: 01:12:34 INFO - 2015-11-02 01:12:34,723 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455554723__endTimestamp 01:12:35 INFO - 2015-11-02 01:12:35,423 INFO : Browser exited with error code: 0 01:12:35 INFO - 2015-11-02 01:12:35,424 INFO : Running cycle 3/20 for ts_paint test... 01:12:35 INFO - 2015-11-02 01:12:35,425 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:36 INFO - 2015-11-02 01:12:36,563 DEBUG : BROWSER_OUTPUT: __start_report1135__end_report 01:12:36 INFO - 2015-11-02 01:12:36,563 DEBUG : BROWSER_OUTPUT: 01:12:36 INFO - 2015-11-02 01:12:36,566 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455556565__endTimestamp 01:12:37 INFO - 2015-11-02 01:12:37,362 INFO : Browser exited with error code: 0 01:12:37 INFO - 2015-11-02 01:12:37,364 INFO : Running cycle 4/20 for ts_paint test... 01:12:37 INFO - 2015-11-02 01:12:37,364 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:38 INFO - 2015-11-02 01:12:38,494 DEBUG : BROWSER_OUTPUT: __start_report1120__end_report 01:12:38 INFO - 2015-11-02 01:12:38,494 DEBUG : BROWSER_OUTPUT: 01:12:38 INFO - 2015-11-02 01:12:38,501 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455558500__endTimestamp 01:12:39 INFO - 2015-11-02 01:12:39,389 INFO : Browser exited with error code: 0 01:12:39 INFO - 2015-11-02 01:12:39,391 INFO : Running cycle 5/20 for ts_paint test... 01:12:39 INFO - 2015-11-02 01:12:39,391 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:40 INFO - 2015-11-02 01:12:40,580 DEBUG : BROWSER_OUTPUT: __start_report1187__end_report 01:12:40 INFO - 2015-11-02 01:12:40,580 DEBUG : BROWSER_OUTPUT: 01:12:40 INFO - 2015-11-02 01:12:40,587 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455560583__endTimestamp 01:12:41 INFO - 2015-11-02 01:12:41,501 INFO : Browser exited with error code: 0 01:12:41 INFO - 2015-11-02 01:12:41,503 INFO : Running cycle 6/20 for ts_paint test... 01:12:41 INFO - 2015-11-02 01:12:41,503 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:42 INFO - 2015-11-02 01:12:42,652 DEBUG : BROWSER_OUTPUT: __start_report1135__end_report 01:12:42 INFO - 2015-11-02 01:12:42,652 DEBUG : BROWSER_OUTPUT: 01:12:42 INFO - 2015-11-02 01:12:42,656 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455562655__endTimestamp 01:12:43 INFO - 2015-11-02 01:12:43,613 INFO : Browser exited with error code: 0 01:12:43 INFO - 2015-11-02 01:12:43,615 INFO : Running cycle 7/20 for ts_paint test... 01:12:43 INFO - 2015-11-02 01:12:43,615 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:44 INFO - 2015-11-02 01:12:44,813 DEBUG : BROWSER_OUTPUT: __start_report1196__end_report 01:12:44 INFO - 2015-11-02 01:12:44,813 DEBUG : BROWSER_OUTPUT: 01:12:44 INFO - 2015-11-02 01:12:44,828 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455564820__endTimestamp 01:12:45 INFO - 2015-11-02 01:12:45,721 INFO : Browser exited with error code: 0 01:12:45 INFO - 2015-11-02 01:12:45,723 INFO : Running cycle 8/20 for ts_paint test... 01:12:45 INFO - 2015-11-02 01:12:45,723 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:46 INFO - 2015-11-02 01:12:46,879 DEBUG : BROWSER_OUTPUT: __start_report1149__end_report 01:12:46 INFO - 2015-11-02 01:12:46,880 DEBUG : BROWSER_OUTPUT: 01:12:46 INFO - 2015-11-02 01:12:46,895 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455566890__endTimestamp 01:12:47 INFO - 2015-11-02 01:12:47,805 INFO : Browser exited with error code: 0 01:12:47 INFO - 2015-11-02 01:12:47,807 INFO : Running cycle 9/20 for ts_paint test... 01:12:47 INFO - 2015-11-02 01:12:47,807 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:48 INFO - 2015-11-02 01:12:48,942 DEBUG : BROWSER_OUTPUT: __start_report1129__end_report 01:12:48 INFO - 2015-11-02 01:12:48,942 DEBUG : BROWSER_OUTPUT: 01:12:48 INFO - 2015-11-02 01:12:48,958 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455568955__endTimestamp 01:12:49 INFO - 2015-11-02 01:12:49,933 INFO : Browser exited with error code: 0 01:12:49 INFO - 2015-11-02 01:12:49,935 INFO : Running cycle 10/20 for ts_paint test... 01:12:49 INFO - 2015-11-02 01:12:49,935 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:51 INFO - 2015-11-02 01:12:51,065 DEBUG : BROWSER_OUTPUT: __start_report1119__end_report 01:12:51 INFO - 2015-11-02 01:12:51,065 DEBUG : BROWSER_OUTPUT: 01:12:51 INFO - 2015-11-02 01:12:51,073 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455571071__endTimestamp 01:12:51 INFO - 2015-11-02 01:12:51,922 INFO : Browser exited with error code: 0 01:12:51 INFO - 2015-11-02 01:12:51,924 INFO : Running cycle 11/20 for ts_paint test... 01:12:51 INFO - 2015-11-02 01:12:51,924 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:53 INFO - 2015-11-02 01:12:53,095 DEBUG : BROWSER_OUTPUT: __start_report1164__end_report 01:12:53 INFO - 2015-11-02 01:12:53,096 DEBUG : BROWSER_OUTPUT: 01:12:53 INFO - 2015-11-02 01:12:53,103 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455573101__endTimestamp 01:12:54 INFO - 2015-11-02 01:12:54,089 INFO : Browser exited with error code: 0 01:12:54 INFO - 2015-11-02 01:12:54,091 INFO : Running cycle 12/20 for ts_paint test... 01:12:54 INFO - 2015-11-02 01:12:54,091 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:55 INFO - 2015-11-02 01:12:55,228 DEBUG : BROWSER_OUTPUT: __start_report1139__end_report 01:12:55 INFO - 2015-11-02 01:12:55,228 DEBUG : BROWSER_OUTPUT: 01:12:55 INFO - 2015-11-02 01:12:55,244 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455575238__endTimestamp 01:12:56 INFO - 2015-11-02 01:12:56,309 INFO : Browser exited with error code: 0 01:12:56 INFO - 2015-11-02 01:12:56,311 INFO : Running cycle 13/20 for ts_paint test... 01:12:56 INFO - 2015-11-02 01:12:56,311 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:57 INFO - 2015-11-02 01:12:57,457 DEBUG : BROWSER_OUTPUT: __start_report1143__end_report 01:12:57 INFO - 2015-11-02 01:12:57,457 DEBUG : BROWSER_OUTPUT: 01:12:57 INFO - 2015-11-02 01:12:57,465 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455577463__endTimestamp 01:12:58 INFO - 2015-11-02 01:12:58,482 INFO : Browser exited with error code: 0 01:12:58 INFO - 2015-11-02 01:12:58,483 INFO : Running cycle 14/20 for ts_paint test... 01:12:58 INFO - 2015-11-02 01:12:58,484 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:12:59 INFO - 2015-11-02 01:12:59,598 DEBUG : BROWSER_OUTPUT: __start_report1105__end_report 01:12:59 INFO - 2015-11-02 01:12:59,598 DEBUG : BROWSER_OUTPUT: 01:12:59 INFO - 2015-11-02 01:12:59,613 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455579607__endTimestamp 01:13:00 INFO - 2015-11-02 01:13:00,649 INFO : Browser exited with error code: 0 01:13:00 INFO - 2015-11-02 01:13:00,651 INFO : Running cycle 15/20 for ts_paint test... 01:13:00 INFO - 2015-11-02 01:13:00,651 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:13:01 INFO - 2015-11-02 01:13:01,781 DEBUG : BROWSER_OUTPUT: __start_report1134__end_report 01:13:01 INFO - 2015-11-02 01:13:01,781 DEBUG : BROWSER_OUTPUT: 01:13:01 INFO - 2015-11-02 01:13:01,782 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455581781__endTimestamp 01:13:02 INFO - 2015-11-02 01:13:02,638 INFO : Browser exited with error code: 0 01:13:02 INFO - 2015-11-02 01:13:02,639 INFO : Running cycle 16/20 for ts_paint test... 01:13:02 INFO - 2015-11-02 01:13:02,640 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:13:03 INFO - 2015-11-02 01:13:03,790 DEBUG : BROWSER_OUTPUT: __start_report1146__end_report 01:13:03 INFO - 2015-11-02 01:13:03,790 DEBUG : BROWSER_OUTPUT: 01:13:03 INFO - 2015-11-02 01:13:03,792 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455583791__endTimestamp 01:13:04 INFO - 2015-11-02 01:13:04,677 INFO : Browser exited with error code: 0 01:13:04 INFO - 2015-11-02 01:13:04,678 INFO : Running cycle 17/20 for ts_paint test... 01:13:04 INFO - 2015-11-02 01:13:04,678 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:13:05 INFO - 2015-11-02 01:13:05,817 DEBUG : BROWSER_OUTPUT: __start_report1133__end_report 01:13:05 INFO - 2015-11-02 01:13:05,817 DEBUG : BROWSER_OUTPUT: 01:13:05 INFO - 2015-11-02 01:13:05,833 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455585827__endTimestamp 01:13:06 INFO - 2015-11-02 01:13:06,765 INFO : Browser exited with error code: 0 01:13:06 INFO - 2015-11-02 01:13:06,767 INFO : Running cycle 18/20 for ts_paint test... 01:13:06 INFO - 2015-11-02 01:13:06,767 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:13:07 INFO - 2015-11-02 01:13:07,890 DEBUG : BROWSER_OUTPUT: __start_report1118__end_report 01:13:07 INFO - 2015-11-02 01:13:07,890 DEBUG : BROWSER_OUTPUT: 01:13:07 INFO - 2015-11-02 01:13:07,906 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455587902__endTimestamp 01:13:08 INFO - 2015-11-02 01:13:08,756 INFO : Browser exited with error code: 0 01:13:08 INFO - 2015-11-02 01:13:08,757 INFO : Running cycle 19/20 for ts_paint test... 01:13:08 INFO - 2015-11-02 01:13:08,757 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:13:09 INFO - 2015-11-02 01:13:09,891 DEBUG : BROWSER_OUTPUT: __start_report1126__end_report 01:13:09 INFO - 2015-11-02 01:13:09,891 DEBUG : BROWSER_OUTPUT: 01:13:09 INFO - 2015-11-02 01:13:09,898 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455589898__endTimestamp 01:13:10 INFO - 2015-11-02 01:13:10,833 INFO : Browser exited with error code: 0 01:13:10 INFO - 2015-11-02 01:13:10,835 INFO : Running cycle 20/20 for ts_paint test... 01:13:10 INFO - 2015-11-02 01:13:10,835 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpiIaGFb/profile http://localhost:36796/startup_test/tspaint_test.html 01:13:11 INFO - 2015-11-02 01:13:11,988 DEBUG : BROWSER_OUTPUT: __start_report1143__end_report 01:13:11 INFO - 2015-11-02 01:13:11,988 DEBUG : BROWSER_OUTPUT: 01:13:11 INFO - 2015-11-02 01:13:11,991 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455591990__endTimestamp 01:13:12 INFO - 2015-11-02 01:13:12,872 INFO : Browser exited with error code: 0 01:13:12 INFO - 2015-11-02 01:13:12,894 INFO : Completed test ts_paint (00:00:47) 01:13:12 INFO - 2015-11-02 01:13:12,894 INFO : Starting test tpaint 01:13:12 INFO - 2015-11-02 01:13:12,894 DEBUG : operating with platform_type : linux_ 01:13:12 INFO - 2015-11-02 01:13:12,894 INFO : Initialising browser for tpaint test... 01:13:12 INFO - 2015-11-02 01:13:12,901 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_9Uzk/profile http://localhost:36796/getInfo.html 01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 01:13:16 INFO - 2015-11-02 01:13:16,741 DEBUG : BROWSER_OUTPUT: __metrics 01:13:16 INFO - 2015-11-02 01:13:16,824 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:13:18 INFO - 2015-11-02 01:13:18,019 INFO : Browser initialized. 01:13:18 INFO - 2015-11-02 01:13:18,020 INFO : Running cycle 1/1 for tpaint test... 01:13:18 INFO - 2015-11-02 01:13:18,020 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp7_9Uzk/profile file:///builds/slave/test/build/tests/talos/talos/startup_test/tpaint.html?auto=1 01:13:44 INFO - 2015-11-02 01:13:44,103 DEBUG : BROWSER_OUTPUT: __start_report201.0650000000005|201.1150000000016|203.23999999999978|205.5300000000002|206.84500000000116|208.90499999999997|209.96000000000004|209.96999999999753|210.86000000000058|212.1949999999997|214.3050000000003|214.375|215.28499999999985|216.44999999999982|219.61999999999898|228.52500000000146|233.22000000000116|239.90499999999884|242.47999999999956|304.71500000000015__end_report__startTimestamp1446455624102__endTimestamp 01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: openingTimes=201.1150000000016,203.23999999999978,205.5300000000002,206.84500000000116,208.90499999999997,209.96000000000004,209.96999999999753,210.86000000000058,212.1949999999997,214.3050000000003,214.375,215.28499999999985,216.44999999999982,219.61999999999898,228.52500000000146,233.22000000000116,239.90499999999884,242.47999999999956,304.71500000000015 01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: avgOpenTime:219.93 01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: minOpenTime:201.07 01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: maxOpenTime:304.72 01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: medOpenTime:213.25 01:13:44 INFO - 2015-11-02 01:13:44,104 DEBUG : BROWSER_OUTPUT: __xulWinOpenTime:213.25 01:13:45 INFO - 2015-11-02 01:13:45,130 INFO : Browser exited with error code: 0 01:13:45 INFO - 2015-11-02 01:13:45,141 INFO : Completed test tpaint (00:00:32) 01:13:45 INFO - 2015-11-02 01:13:45,141 INFO : Starting test sessionrestore 01:13:45 INFO - 2015-11-02 01:13:45,141 DEBUG : operating with platform_type : linux_ 01:13:45 INFO - 2015-11-02 01:13:45,141 INFO : Initialising browser for sessionrestore test... 01:13:45 INFO - 2015-11-02 01:13:45,150 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/getInfo.html 01:13:52 INFO - 2015-11-02 01:13:51,999 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:13:52 INFO - 2015-11-02 01:13:52,000 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:13:52 INFO - 2015-11-02 01:13:52,000 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1151/811 01:13:52 INFO - 2015-11-02 01:13:52,000 DEBUG : BROWSER_OUTPUT: __metrics 01:13:52 INFO - 2015-11-02 01:13:52,136 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:13:52 INFO - 2015-11-02 01:13:52,865 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/IndexedDBHelper.jsm, line 138: UnknownError: The operation failed for reasons unrelated to the database itself and not covered by any other error code. 01:13:53 INFO - 2015-11-02 01:13:53,629 INFO : Browser initialized. 01:13:53 INFO - 2015-11-02 01:13:53,630 INFO : Running cycle 1/10 for sessionrestore test... 01:13:53 INFO - 2015-11-02 01:13:53,630 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:13:53 INFO - 2015-11-02 01:13:53,632 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:13:53 INFO - 2015-11-02 01:13:53,633 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:13:58 INFO - 2015-11-02 01:13:58,517 DEBUG : BROWSER_OUTPUT: __start_report2557__end_report 01:13:58 INFO - 2015-11-02 01:13:58,517 DEBUG : BROWSER_OUTPUT: 01:13:58 INFO - 2015-11-02 01:13:58,518 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455638513__endTimestamp 01:13:58 INFO - 2015-11-02 01:13:58,518 DEBUG : BROWSER_OUTPUT: 01:14:00 INFO - 2015-11-02 01:14:00,085 INFO : Browser exited with error code: 0 01:14:00 INFO - 2015-11-02 01:14:00,087 INFO : Running cycle 2/10 for sessionrestore test... 01:14:00 INFO - 2015-11-02 01:14:00,087 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:00 INFO - 2015-11-02 01:14:00,089 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:00 INFO - 2015-11-02 01:14:00,090 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT: __start_report2181__end_report 01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT: 01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455644281__endTimestamp 01:14:04 INFO - 2015-11-02 01:14:04,285 DEBUG : BROWSER_OUTPUT: 01:14:05 INFO - 2015-11-02 01:14:05,989 INFO : Browser exited with error code: 0 01:14:05 INFO - 2015-11-02 01:14:05,991 INFO : Running cycle 3/10 for sessionrestore test... 01:14:05 INFO - 2015-11-02 01:14:05,991 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:05 INFO - 2015-11-02 01:14:05,993 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:05 INFO - 2015-11-02 01:14:05,994 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:10 INFO - 2015-11-02 01:14:10,157 DEBUG : BROWSER_OUTPUT: __start_report2183__end_report 01:14:10 INFO - 2015-11-02 01:14:10,158 DEBUG : BROWSER_OUTPUT: 01:14:10 INFO - 2015-11-02 01:14:10,158 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455650156__endTimestamp 01:14:10 INFO - 2015-11-02 01:14:10,158 DEBUG : BROWSER_OUTPUT: 01:14:11 INFO - 2015-11-02 01:14:11,801 INFO : Browser exited with error code: 0 01:14:11 INFO - 2015-11-02 01:14:11,803 INFO : Running cycle 4/10 for sessionrestore test... 01:14:11 INFO - 2015-11-02 01:14:11,803 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:11 INFO - 2015-11-02 01:14:11,805 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:11 INFO - 2015-11-02 01:14:11,806 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT: __start_report2196__end_report 01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT: 01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455655978__endTimestamp 01:14:15 INFO - 2015-11-02 01:14:15,979 DEBUG : BROWSER_OUTPUT: 01:14:17 INFO - 2015-11-02 01:14:17,733 INFO : Browser exited with error code: 0 01:14:17 INFO - 2015-11-02 01:14:17,735 INFO : Running cycle 5/10 for sessionrestore test... 01:14:17 INFO - 2015-11-02 01:14:17,735 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:17 INFO - 2015-11-02 01:14:17,737 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:17 INFO - 2015-11-02 01:14:17,738 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:21 INFO - 2015-11-02 01:14:21,970 DEBUG : BROWSER_OUTPUT: __start_report2218__end_report 01:14:21 INFO - 2015-11-02 01:14:21,971 DEBUG : BROWSER_OUTPUT: 01:14:21 INFO - 2015-11-02 01:14:21,971 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455661966__endTimestamp 01:14:21 INFO - 2015-11-02 01:14:21,971 DEBUG : BROWSER_OUTPUT: 01:14:23 INFO - 2015-11-02 01:14:23,729 INFO : Browser exited with error code: 0 01:14:23 INFO - 2015-11-02 01:14:23,731 INFO : Running cycle 6/10 for sessionrestore test... 01:14:23 INFO - 2015-11-02 01:14:23,731 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:23 INFO - 2015-11-02 01:14:23,733 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:23 INFO - 2015-11-02 01:14:23,734 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT: __start_report2158__end_report 01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT: 01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455667866__endTimestamp 01:14:27 INFO - 2015-11-02 01:14:27,870 DEBUG : BROWSER_OUTPUT: 01:14:29 INFO - 2015-11-02 01:14:29,641 INFO : Browser exited with error code: 0 01:14:29 INFO - 2015-11-02 01:14:29,643 INFO : Running cycle 7/10 for sessionrestore test... 01:14:29 INFO - 2015-11-02 01:14:29,643 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:29 INFO - 2015-11-02 01:14:29,645 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:29 INFO - 2015-11-02 01:14:29,646 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT: __start_report2191__end_report 01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT: 01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455673834__endTimestamp 01:14:33 INFO - 2015-11-02 01:14:33,834 DEBUG : BROWSER_OUTPUT: 01:14:35 INFO - 2015-11-02 01:14:35,593 INFO : Browser exited with error code: 0 01:14:35 INFO - 2015-11-02 01:14:35,595 INFO : Running cycle 8/10 for sessionrestore test... 01:14:35 INFO - 2015-11-02 01:14:35,595 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:35 INFO - 2015-11-02 01:14:35,597 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:35 INFO - 2015-11-02 01:14:35,598 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT: __start_report2206__end_report 01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT: 01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455679798__endTimestamp 01:14:39 INFO - 2015-11-02 01:14:39,799 DEBUG : BROWSER_OUTPUT: 01:14:41 INFO - 2015-11-02 01:14:41,597 INFO : Browser exited with error code: 0 01:14:41 INFO - 2015-11-02 01:14:41,599 INFO : Running cycle 9/10 for sessionrestore test... 01:14:41 INFO - 2015-11-02 01:14:41,599 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:41 INFO - 2015-11-02 01:14:41,601 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:41 INFO - 2015-11-02 01:14:41,602 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:45 INFO - 2015-11-02 01:14:45,736 DEBUG : BROWSER_OUTPUT: __start_report2140__end_report 01:14:45 INFO - 2015-11-02 01:14:45,736 DEBUG : BROWSER_OUTPUT: 01:14:45 INFO - 2015-11-02 01:14:45,736 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455685732__endTimestamp 01:14:45 INFO - 2015-11-02 01:14:45,737 DEBUG : BROWSER_OUTPUT: 01:14:47 INFO - 2015-11-02 01:14:47,533 INFO : Browser exited with error code: 0 01:14:47 INFO - 2015-11-02 01:14:47,535 INFO : Running cycle 10/10 for sessionrestore test... 01:14:47 INFO - 2015-11-02 01:14:47,535 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmpxXC0Yo/profile/sessionstore.js 01:14:47 INFO - 2015-11-02 01:14:47,537 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmpxXC0Yo/profile/sessionCheckpoints.json 01:14:47 INFO - 2015-11-02 01:14:47,538 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpxXC0Yo/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT: __start_report2153__end_report 01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT: 01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455691712__endTimestamp 01:14:51 INFO - 2015-11-02 01:14:51,714 DEBUG : BROWSER_OUTPUT: 01:14:53 INFO - 2015-11-02 01:14:53,513 INFO : Browser exited with error code: 0 01:14:53 INFO - 2015-11-02 01:14:53,532 INFO : Completed test sessionrestore (00:01:08) 01:14:53 INFO - 2015-11-02 01:14:53,532 INFO : Starting test sessionrestore_no_auto_restore 01:14:53 INFO - 2015-11-02 01:14:53,532 DEBUG : operating with platform_type : linux_ 01:14:53 INFO - 2015-11-02 01:14:53,532 INFO : Initialising browser for sessionrestore_no_auto_restore test... 01:14:53 INFO - 2015-11-02 01:14:53,540 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/getInfo.html 01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: colorDepth:24 01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1151/811 01:14:57 INFO - 2015-11-02 01:14:57,157 DEBUG : BROWSER_OUTPUT: __metrics 01:14:57 INFO - 2015-11-02 01:14:57,265 DEBUG : BROWSER_OUTPUT: JavaScript error: resource:///modules/WebappManager.jsm, line 48: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:14:58 INFO - 2015-11-02 01:14:58,560 INFO : Browser initialized. 01:14:58 INFO - 2015-11-02 01:14:58,561 INFO : Running cycle 1/10 for sessionrestore_no_auto_restore test... 01:14:58 INFO - 2015-11-02 01:14:58,561 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:14:58 INFO - 2015-11-02 01:14:58,563 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:14:58 INFO - 2015-11-02 01:14:58,564 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:00 INFO - 2015-11-02 01:15:00,279 DEBUG : BROWSER_OUTPUT: __start_report1073__end_report 01:15:00 INFO - 2015-11-02 01:15:00,280 DEBUG : BROWSER_OUTPUT: 01:15:00 INFO - 2015-11-02 01:15:00,280 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455700274__endTimestamp 01:15:00 INFO - 2015-11-02 01:15:00,280 DEBUG : BROWSER_OUTPUT: 01:15:01 INFO - 2015-11-02 01:15:01,152 INFO : Browser exited with error code: 0 01:15:01 INFO - 2015-11-02 01:15:01,154 INFO : Running cycle 2/10 for sessionrestore_no_auto_restore test... 01:15:01 INFO - 2015-11-02 01:15:01,154 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:01 INFO - 2015-11-02 01:15:01,156 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:01 INFO - 2015-11-02 01:15:01,156 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT: __start_report785__end_report 01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT: 01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455702436__endTimestamp 01:15:02 INFO - 2015-11-02 01:15:02,439 DEBUG : BROWSER_OUTPUT: 01:15:03 INFO - 2015-11-02 01:15:03,417 INFO : Browser exited with error code: 0 01:15:03 INFO - 2015-11-02 01:15:03,419 INFO : Running cycle 3/10 for sessionrestore_no_auto_restore test... 01:15:03 INFO - 2015-11-02 01:15:03,419 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:03 INFO - 2015-11-02 01:15:03,421 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:03 INFO - 2015-11-02 01:15:03,421 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT: __start_report832__end_report 01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT: 01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455704728__endTimestamp 01:15:04 INFO - 2015-11-02 01:15:04,735 DEBUG : BROWSER_OUTPUT: 01:15:05 INFO - 2015-11-02 01:15:05,737 INFO : Browser exited with error code: 0 01:15:05 INFO - 2015-11-02 01:15:05,739 INFO : Running cycle 4/10 for sessionrestore_no_auto_restore test... 01:15:05 INFO - 2015-11-02 01:15:05,739 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:05 INFO - 2015-11-02 01:15:05,741 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:05 INFO - 2015-11-02 01:15:05,741 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT: __start_report758__end_report 01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT: 01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455706977__endTimestamp 01:15:06 INFO - 2015-11-02 01:15:06,978 DEBUG : BROWSER_OUTPUT: 01:15:07 INFO - 2015-11-02 01:15:07,993 INFO : Browser exited with error code: 0 01:15:07 INFO - 2015-11-02 01:15:07,995 INFO : Running cycle 5/10 for sessionrestore_no_auto_restore test... 01:15:07 INFO - 2015-11-02 01:15:07,995 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:07 INFO - 2015-11-02 01:15:07,997 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:07 INFO - 2015-11-02 01:15:07,997 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:09 INFO - 2015-11-02 01:15:09,305 DEBUG : BROWSER_OUTPUT: __start_report818__end_report 01:15:09 INFO - 2015-11-02 01:15:09,305 DEBUG : BROWSER_OUTPUT: 01:15:09 INFO - 2015-11-02 01:15:09,306 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455709302__endTimestamp 01:15:09 INFO - 2015-11-02 01:15:09,306 DEBUG : BROWSER_OUTPUT: 01:15:10 INFO - 2015-11-02 01:15:10,325 INFO : Browser exited with error code: 0 01:15:10 INFO - 2015-11-02 01:15:10,327 INFO : Running cycle 6/10 for sessionrestore_no_auto_restore test... 01:15:10 INFO - 2015-11-02 01:15:10,327 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:10 INFO - 2015-11-02 01:15:10,329 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:10 INFO - 2015-11-02 01:15:10,330 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT: __start_report772__end_report 01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT: 01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455711549__endTimestamp 01:15:11 INFO - 2015-11-02 01:15:11,552 DEBUG : BROWSER_OUTPUT: 01:15:12 INFO - 2015-11-02 01:15:12,601 INFO : Browser exited with error code: 0 01:15:12 INFO - 2015-11-02 01:15:12,603 INFO : Running cycle 7/10 for sessionrestore_no_auto_restore test... 01:15:12 INFO - 2015-11-02 01:15:12,603 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:12 INFO - 2015-11-02 01:15:12,605 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:12 INFO - 2015-11-02 01:15:12,606 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:13 INFO - 2015-11-02 01:15:13,940 DEBUG : BROWSER_OUTPUT: __start_report843__end_report 01:15:13 INFO - 2015-11-02 01:15:13,940 DEBUG : BROWSER_OUTPUT: 01:15:13 INFO - 2015-11-02 01:15:13,941 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455713936__endTimestamp 01:15:13 INFO - 2015-11-02 01:15:13,941 DEBUG : BROWSER_OUTPUT: 01:15:14 INFO - 2015-11-02 01:15:14,981 INFO : Browser exited with error code: 0 01:15:14 INFO - 2015-11-02 01:15:14,983 INFO : Running cycle 8/10 for sessionrestore_no_auto_restore test... 01:15:14 INFO - 2015-11-02 01:15:14,983 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:14 INFO - 2015-11-02 01:15:14,985 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:14 INFO - 2015-11-02 01:15:14,986 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT: __start_report786__end_report 01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT: 01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455716236__endTimestamp 01:15:16 INFO - 2015-11-02 01:15:16,237 DEBUG : BROWSER_OUTPUT: 01:15:17 INFO - 2015-11-02 01:15:17,337 INFO : Browser exited with error code: 0 01:15:17 INFO - 2015-11-02 01:15:17,339 INFO : Running cycle 9/10 for sessionrestore_no_auto_restore test... 01:15:17 INFO - 2015-11-02 01:15:17,339 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:17 INFO - 2015-11-02 01:15:17,341 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:17 INFO - 2015-11-02 01:15:17,342 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:18 INFO - 2015-11-02 01:15:18,653 DEBUG : BROWSER_OUTPUT: __start_report811__end_report 01:15:18 INFO - 2015-11-02 01:15:18,653 DEBUG : BROWSER_OUTPUT: 01:15:18 INFO - 2015-11-02 01:15:18,654 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455718649__endTimestamp 01:15:18 INFO - 2015-11-02 01:15:18,654 DEBUG : BROWSER_OUTPUT: 01:15:19 INFO - 2015-11-02 01:15:19,673 INFO : Browser exited with error code: 0 01:15:19 INFO - 2015-11-02 01:15:19,675 INFO : Running cycle 10/10 for sessionrestore_no_auto_restore test... 01:15:19 INFO - 2015-11-02 01:15:19,675 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionstore.js on top of /tmp/tmp1HPSko/profile/sessionstore.js 01:15:19 INFO - 2015-11-02 01:15:19,677 DEBUG : Reinstalling /builds/slave/test/build/tests/talos/talos/startup_test/sessionrestore/profile/sessionCheckpoints.json on top of /tmp/tmp1HPSko/profile/sessionCheckpoints.json 01:15:19 INFO - 2015-11-02 01:15:19,677 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1HPSko/profile http://localhost:36796/startup_test/sessionrestore/index.html 01:15:20 INFO - 2015-11-02 01:15:20,952 DEBUG : BROWSER_OUTPUT: __start_report806__end_report 01:15:20 INFO - 2015-11-02 01:15:20,952 DEBUG : BROWSER_OUTPUT: 01:15:20 INFO - 2015-11-02 01:15:20,953 DEBUG : BROWSER_OUTPUT: __startTimestamp1446455720952__endTimestamp 01:15:20 INFO - 2015-11-02 01:15:20,953 DEBUG : BROWSER_OUTPUT: 01:15:22 INFO - 2015-11-02 01:15:22,029 INFO : Browser exited with error code: 0 01:15:22 INFO - 2015-11-02 01:15:22,042 INFO : Completed test sessionrestore_no_auto_restore (00:00:28) 01:15:22 INFO - 2015-11-02 01:15:22,353 INFO : Completed test suite (00:04:46) 01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Working with test: a11yr 01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Generating results file: a11yr 01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Working with test: ts_paint 01:15:22 INFO - 2015-11-02 01:15:22,354 DEBUG : Generating results file: ts_paint 01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Working with test: tpaint 01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Generating results file: tpaint 01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Working with test: sessionrestore 01:15:22 INFO - 2015-11-02 01:15:22,355 DEBUG : Generating results file: sessionrestore 01:15:22 INFO - 2015-11-02 01:15:22,356 DEBUG : Working with test: sessionrestore_no_auto_restore 01:15:22 INFO - 2015-11-02 01:15:22,356 DEBUG : Generating results file: sessionrestore_no_auto_restore 01:15:22 INFO - 2015-11-02 01:15:22,356 INFO : Posting result 0 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:15:22 INFO - 2015-11-02 01:15:22,526 DEBUG : process_Request line: a11yr_paint graph.html#tests=[[223,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,526 DEBUG : process_Request line: a11yr_paint 683.95 graph.html#tests=[[223,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,526 INFO : Posting result 1 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:15:22 INFO - 2015-11-02 01:15:22,584 DEBUG : process_Request line: ts_paint graph.html#tests=[[83,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,585 DEBUG : process_Request line: ts_paint 1158.97 graph.html#tests=[[83,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,585 INFO : Posting result 2 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:15:22 INFO - 2015-11-02 01:15:22,715 DEBUG : process_Request line: tpaint graph.html#tests=[[82,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,715 DEBUG : process_Request line: tpaint 215.28 graph.html#tests=[[82,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,715 INFO : Posting result 3 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:15:22 INFO - 2015-11-02 01:15:22,762 DEBUG : process_Request line: sessionrestore graph.html#tests=[[313,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,762 DEBUG : process_Request line: sessionrestore 2215.54 graph.html#tests=[[313,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,762 INFO : Posting result 4 of 5 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 01:15:22 INFO - 2015-11-02 01:15:22,808 DEBUG : process_Request line: sessionrestore_no_auto_restore graph.html#tests=[[315,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,808 DEBUG : process_Request line: sessionrestore_no_auto_restore 824.57 graph.html#tests=[[315,132,35]] 01:15:22 INFO - 2015-11-02 01:15:22,809 INFO : TALOSDATA: [{"talos_counters": {}, "results": {"dhtml.html": [1385.0, 1435.0, 1448.0, 1447.0, 1545.0, 1525.0, 1446.0, 1523.0, 1354.0, 1355.0, 1531.0, 1441.0, 1352.0, 1443.0, 1449.0, 1446.0, 1452.0, 1352.0, 1446.0, 1447.0, 1351.0, 1532.0, 1474.0, 1537.0, 1462.0], "tablemutation.html": [330.0, 321.0, 321.0, 324.0, 322.0, 322.0, 324.0, 326.0, 322.0, 323.0, 321.0, 323.0, 322.0, 323.0, 323.0, 324.0, 493.0, 324.0, 320.0, 324.0, 322.0, 320.0, 324.0, 324.0, 322.0]}, "summary": {"suite": 683.9467132558559, "subtests": {"dhtml.html": {"filtered": 1447.0, "value": 1447.0}, "tablemutation.html": {"filtered": 323.0, "value": 323.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "a11yr", "options": {"responsiveness": false, "tpmozafterpaint": true, "tpchrome": true, "tppagecycles": 25, "tpcycles": 1, "tprender": false, "shutdown": false, "cycles": 1, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"ts_paint": [1614.0, 1123.0, 1135.0, 1120.0, 1187.0, 1135.0, 1196.0, 1149.0, 1129.0, 1119.0, 1164.0, 1139.0, 1143.0, 1105.0, 1134.0, 1146.0, 1133.0, 1118.0, 1126.0, 1143.0]}, "summary": {"suite": 1135.0, "subtests": {"ts_paint": {"filtered": 1135.0, "value": 1135.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "ts_paint", "options": {"responsiveness": false, "cycles": 20, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"tpaint": [201.0650000000005, 201.1150000000016, 203.23999999999978, 205.5300000000002, 206.84500000000116, 208.90499999999997, 209.96000000000004, 209.96999999999753, 210.86000000000058, 212.1949999999997, 214.3050000000003, 214.375, 215.28499999999985, 216.44999999999982, 219.61999999999898, 228.52500000000146, 233.22000000000116, 239.90499999999884, 242.47999999999956, 304.71500000000015]}, "summary": {"suite": 215.28499999999985, "subtests": {"tpaint": {"filtered": 215.28499999999985, "value": 215.28499999999985}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "tpaint", "options": {"responsiveness": false, "cycles": 1, "tpmozafterpaint": true, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"sessionrestore": [2557.0, 2181.0, 2183.0, 2196.0, 2218.0, 2158.0, 2191.0, 2206.0, 2140.0, 2153.0]}, "summary": {"suite": 2183.0, "subtests": {"sessionrestore": {"filtered": 2183.0, "value": 2183.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "sessionrestore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}, {"talos_counters": {}, "results": {"sessionrestore_no_auto_restore": [1073.0, 785.0, 832.0, 758.0, 818.0, 772.0, 843.0, 786.0, 811.0, 806.0]}, "summary": {"suite": 806.0, "subtests": {"sessionrestore_no_auto_restore": {"filtered": 806.0, "value": 806.0}}}, "test_machine": {"platform": "x86_64", "osversion": "Ubuntu 12.04", "os": "linux", "name": "talos-linux64-ix-043"}, "testrun": {"date": 1446455435, "suite": "sessionrestore_no_auto_restore", "options": {"responsiveness": false, "cycles": 10, "tpmozafterpaint": false, "shutdown": false, "rss": false}}, "test_build": {"name": "Firefox", "version": "45.0a1", "id": "20151102000831", "branch": "Fx-Team-Non-PGO", "revision": "83ee1cb04baaedfa97074a651ce34c58973ff5e4"}}] 01:15:22 INFO - RETURN: a11yr_paint: 683.95 01:15:22 INFO - RETURN: ts_paint: 1158.97 01:15:22 INFO - RETURN: tpaint: 215.28 01:15:22 INFO - RETURN: sessionrestore: 2215.54 01:15:22 INFO - RETURN: sessionrestore_no_auto_restore: 824.57 01:15:22 INFO - TinderboxPrint: TalosResult: {"graphserver": {"sessionrestore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[313,132,35]]", "result": "2215.54"}, "a11yr_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[223,132,35]]", "result": "683.95"}, "tpaint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[82,132,35]]", "result": "215.28"}, "ts_paint": {"url": "http://graphs.mozilla.org/graph.html#tests=[[83,132,35]]", "result": "1158.97"}, "sessionrestore_no_auto_restore": {"url": "http://graphs.mozilla.org/graph.html#tests=[[315,132,35]]", "result": "824.57"}}} 01:15:22 INFO - Return code: 0 01:15:22 INFO - # TBPL SUCCESS # 01:15:22 INFO - Running post-action listener: _resource_record_post_action 01:15:22 INFO - Running post-run listener: _resource_record_post_run 01:15:23 INFO - Total resource usage - Wall time: 302s; CPU: 13.0%; Read bytes: 7680000; Write bytes: 404230144; Read time: 2240; Write time: 281256 01:15:23 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 62767104; Read time: 0; Write time: 65112 01:15:23 INFO - run-tests - Wall time: 288s; CPU: 13.0%; Read bytes: 5943296; Write bytes: 290496512; Read time: 2124; Write time: 153116 01:15:23 INFO - Running post-run listener: _upload_blobber_files 01:15:23 INFO - Blob upload gear active. 01:15:23 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 01:15:23 INFO - Copying logs to upload dir... 01:15:23 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=349.849839 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 5 mins, 49 secs) (at 2015-11-02 01:15:23.959904) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:23.963568) ========= bash -c 'for file in `ls -1`; do cat $file; done' in dir /builds/slave/test/properties (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'for file in `ls -1`; do cat $file; done'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test/properties SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.009956 build_url: 'https://queue.taskcluster.net/v1/task/laWfc9YFS2iYcp8-4w6lPQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2' ========= master_lag: 0.04 ========= ========= Finished set props: build_url (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:24.015221) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:24.015581) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=ae8083fbfc14dfa356ee9053000001f7-1446454835.140225-297229588 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004511 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-11-02 01:15:24.065029) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-02 01:15:24.065471) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-11-02 01:15:24.065942) ========= ========= Total master_lag: 0.39 =========