builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-060 starttime: 1449972693.2 results: success (0) buildid: 20151212165532 builduid: c6eaa5a52cc449fe9910ffbd55ac68b8 revision: f07e71078bc8991f74c2101944c8f869c77f442a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.201136) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.201647) ========= ========= Started set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.201947) ========= 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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False /builds/slave/test program finished with exit code 0 elapsedTime=0.006283 basedir: '/builds/slave/test' ========= master_lag: 0.02 ========= ========= Finished set props: basedir (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.229252) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.229627) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.257659) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.257973) ========= 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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004661 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.308298) ========= ========= Started set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.308681) ========= script_repo_url: https://hg.mozilla.org/build/mozharness ========= Finished set props: script_repo_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.309063) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.309516) ========= 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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2015-12-12 18:11:35-- 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% 43.4M=0s 2015-12-12 18:11:35 (43.4 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.186030 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.508755) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.509075) ========= 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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.013494 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.560295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:33.560742) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f07e71078bc8991f74c2101944c8f869c77f442a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev f07e71078bc8991f74c2101944c8f869c77f442a --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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2015-12-12 18:11:35,311 truncating revision to first 12 chars 2015-12-12 18:11:35,311 Setting DEBUG logging. 2015-12-12 18:11:35,311 attempt 1/10 2015-12-12 18:11:35,311 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f07e71078bc8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2015-12-12 18:11:35,563 unpacking tar archive at: mozilla-central-f07e71078bc8/testing/mozharness/ program finished with exit code 0 elapsedTime=0.554851 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:34.129511) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:34.129830) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:34.139938) ========= ========= Started tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:34.140323) ========= TinderboxPrint: script_revlink: https://hg.mozilla.org/build/mozharness/rev/production ========= Finished tinderboxprint_script_revlink (results: 0, elapsed: 0 secs) (at 2015-12-12 18:11:34.140789) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-12 18:11:34.141072) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox-Non-PGO', '--system-bits', '64', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 18:11:35 INFO - MultiFileLogger online at 20151212 18:11:35 in /builds/slave/test 18:11:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --system-bits 64 --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 18:11:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:11:35 INFO - {'append_to_log': False, 18:11:35 INFO - 'base_work_dir': '/builds/slave/test', 18:11:35 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 18:11:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:11:35 INFO - 'branch': 'Firefox-Non-PGO', 18:11:35 INFO - 'buildbot_json_path': 'buildprops.json', 18:11:35 INFO - 'config_files': ('talos/linux_config.py',), 18:11:35 INFO - 'default_actions': ('clobber', 18:11:35 INFO - 'read-buildbot-config', 18:11:35 INFO - 'download-and-extract', 18:11:35 INFO - 'populate-webroot', 18:11:35 INFO - 'create-virtualenv', 18:11:35 INFO - 'install', 18:11:35 INFO - 'run-tests'), 18:11:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:11:35 INFO - 'download_minidump_stackwalk': True, 18:11:35 INFO - 'download_symbols': 'ondemand', 18:11:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:11:35 INFO - 'tooltool.py': '/tools/tooltool.py', 18:11:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:11:35 INFO - '/tools/misc-python/virtualenv.py')}, 18:11:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:11:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:11:35 INFO - 'installer_path': 'installer.exe', 18:11:35 INFO - 'log_level': 'info', 18:11:35 INFO - 'log_name': 'talos', 18:11:35 INFO - 'log_to_console': True, 18:11:35 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 18:11:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 18:11:35 INFO - 'opt_config_files': (), 18:11:35 INFO - 'pip_index': False, 18:11:35 INFO - 'sps_profile': False, 18:11:35 INFO - 'sps_profile_interval': 0, 18:11:35 INFO - 'suite': 'dromaeojs-e10s', 18:11:35 INFO - 'system_bits': '64', 18:11:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:11:35 INFO - 'title': 'talos-linux64-ix-060', 18:11:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:11:35 INFO - 'use_talos_json': True, 18:11:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:11:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:11:35 INFO - 'work_dir': 'build'} 18:11:35 INFO - ##### 18:11:35 INFO - ##### Running clobber step. 18:11:35 INFO - ##### 18:11:35 INFO - Running pre-action listener: _resource_record_pre_action 18:11:35 INFO - Running main action method: clobber 18:11:35 INFO - rmtree: /builds/slave/test/build 18:11:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:11:36 INFO - Running post-action listener: _resource_record_post_action 18:11:36 INFO - ##### 18:11:36 INFO - ##### Running read-buildbot-config step. 18:11:36 INFO - ##### 18:11:36 INFO - Running pre-action listener: _resource_record_pre_action 18:11:36 INFO - Running main action method: read_buildbot_config 18:11:36 INFO - Using buildbot properties: 18:11:36 INFO - { 18:11:36 INFO - "properties": { 18:11:36 INFO - "buildnumber": 118, 18:11:36 INFO - "product": "firefox", 18:11:36 INFO - "script_repo_revision": "production", 18:11:36 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 18:11:36 INFO - "repository": "", 18:11:36 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 18:11:36 INFO - "buildid": "20151212165532", 18:11:36 INFO - "slavename": "talos-linux64-ix-060", 18:11:36 INFO - "pgo_build": "False", 18:11:36 INFO - "basedir": "/builds/slave/test", 18:11:36 INFO - "project": "", 18:11:36 INFO - "platform": "ubuntu64_hw", 18:11:36 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 18:11:36 INFO - "slavebuilddir": "test", 18:11:36 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 18:11:36 INFO - "branch": "mozilla-central", 18:11:36 INFO - "repo_path": "mozilla-central", 18:11:36 INFO - "stage_platform": "linux64", 18:11:36 INFO - "builduid": "c6eaa5a52cc449fe9910ffbd55ac68b8", 18:11:36 INFO - "revision": "f07e71078bc8991f74c2101944c8f869c77f442a" 18:11:36 INFO - }, 18:11:36 INFO - "sourcestamp": { 18:11:36 INFO - "repository": "", 18:11:36 INFO - "hasPatch": false, 18:11:36 INFO - "project": "", 18:11:36 INFO - "branch": "mozilla-central-linux64-talos", 18:11:36 INFO - "changes": [ 18:11:36 INFO - { 18:11:36 INFO - "category": null, 18:11:36 INFO - "files": [ 18:11:36 INFO - { 18:11:36 INFO - "url": null, 18:11:36 INFO - "name": "https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2" 18:11:36 INFO - } 18:11:36 INFO - ], 18:11:36 INFO - "repository": "", 18:11:36 INFO - "rev": "f07e71078bc8991f74c2101944c8f869c77f442a", 18:11:36 INFO - "who": "philringnalda@gmail.com", 18:11:36 INFO - "when": 1449972621, 18:11:36 INFO - "number": 7355443, 18:11:36 INFO - "comments": "Bug 1167155 etc. - skip abspos writing-mode reftests on Windows 7 for frequent waiting for onload scripts to complete timeouts, a=testonly", 18:11:36 INFO - "project": "", 18:11:36 INFO - "at": "Sat 12 Dec 2015 18:10:21", 18:11:36 INFO - "branch": "mozilla-central-linux64-talos", 18:11:36 INFO - "revlink": "", 18:11:36 INFO - "properties": [ 18:11:36 INFO - [ 18:11:36 INFO - "buildid", 18:11:36 INFO - "20151212165532", 18:11:36 INFO - "Change" 18:11:36 INFO - ], 18:11:36 INFO - [ 18:11:36 INFO - "builduid", 18:11:36 INFO - "c6eaa5a52cc449fe9910ffbd55ac68b8", 18:11:36 INFO - "Change" 18:11:36 INFO - ], 18:11:36 INFO - [ 18:11:36 INFO - "pgo_build", 18:11:36 INFO - "False", 18:11:36 INFO - "Change" 18:11:36 INFO - ] 18:11:36 INFO - ], 18:11:36 INFO - "revision": "f07e71078bc8991f74c2101944c8f869c77f442a" 18:11:36 INFO - } 18:11:36 INFO - ], 18:11:36 INFO - "revision": "f07e71078bc8991f74c2101944c8f869c77f442a" 18:11:36 INFO - } 18:11:36 INFO - } 18:11:36 INFO - Found installer url https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2. 18:11:36 INFO - Running post-action listener: _resource_record_post_action 18:11:36 INFO - ##### 18:11:36 INFO - ##### Running download-and-extract step. 18:11:36 INFO - ##### 18:11:36 INFO - Running pre-action listener: _resource_record_pre_action 18:11:36 INFO - Running main action method: download_and_extract 18:11:36 INFO - mkdir: /builds/slave/test/build/tests 18:11:36 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')]} 18:11:36 INFO - https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/test_packages.json matches https://queue.taskcluster.net 18:11:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/test_packages.json 18:11:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/test_packages.json 18:11:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/test_packages.json to /builds/slave/test/build/test_packages.json 18:11:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/test_packages.json', 'file_name': '/builds/slave/test/build/test_packages.json'}, attempt #1 18:11:41 INFO - Downloaded 1302 bytes. 18:11:41 INFO - Reading from file /builds/slave/test/build/test_packages.json 18:11:41 INFO - Using the following test package requirements: 18:11:41 INFO - {u'common': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:11:41 INFO - u'cppunittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 18:11:41 INFO - u'jittest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'jsshell-linux-x86_64.zip'], 18:11:41 INFO - u'mochitest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 18:11:41 INFO - u'mozbase': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:11:41 INFO - u'reftest': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.reftest.tests.zip'], 18:11:41 INFO - u'talos': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip'], 18:11:41 INFO - u'web-platform': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 18:11:41 INFO - u'webapprt': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'], 18:11:41 INFO - u'xpcshell': [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip', 18:11:41 INFO - u'firefox-45.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 18:11:41 INFO - Downloading packages: [u'firefox-45.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 18:11: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')]} 18:11:41 INFO - https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:11:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:11:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:11:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:51 INFO - Downloaded 21869713 bytes. 18:11:51 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 18:11:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:11:51 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 18:11:52 INFO - Return code: 0 18:11:52 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 18:11:52 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')]} 18:11:52 INFO - https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 18:11:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:11:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:11:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:53 INFO - Downloaded 21869713 bytes. 18:11:53 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 18:11:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.common.tests.zip 18:11:53 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 18:11:54 INFO - Return code: 0 18:11:54 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')]} 18:11:54 INFO - https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 18:11:54 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:11:54 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:11:54 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:55 INFO - Downloaded 11132038 bytes. 18:11:55 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 18:11:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-45.0a1.en-US.linux-x86_64.talos.tests.zip 18:11:55 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 18:11:55 INFO - Return code: 0 18:11:55 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')]} 18:11:55 INFO - https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 18:11:55 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:11:55 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:11:55 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/2M5W4wm7TF-bTTziG0digQ/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 18:11:57 INFO - Downloaded 57740873 bytes. 18:11:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:11:57 INFO - mkdir: /builds/slave/test/properties 18:11:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:11:57 INFO - Writing to file /builds/slave/test/properties/build_url 18:11:57 INFO - Contents: 18:11:57 INFO - build_url:https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 18:11:57 INFO - Running post-action listener: _resource_record_post_action 18:11:57 INFO - Running post-action listener: set_extra_try_arguments 18:11:57 INFO - ##### 18:11:57 INFO - ##### Running populate-webroot step. 18:11:57 INFO - ##### 18:11:57 INFO - Running pre-action listener: _resource_record_pre_action 18:11:57 INFO - Running main action method: populate_webroot 18:11:57 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:11:57 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:11:57 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:11:57 INFO - '--tppagecycles', 18:11:57 INFO - '7'], 18:11:57 INFO - 'tests': ['tsvgm']}}, 18:11:57 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 18:11:57 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:11:57 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:11:57 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['dromaeo_css', 'kraken', 'v8_7']}, 18:11:57 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:57 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:11:57 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'tests': ['damp', 'tps']}, 18:11:57 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['damp', 'tps']}, 18:11:57 INFO - 'g3': {'tests': ['dromaeo_dom']}, 18:11:57 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:11:57 INFO - 'other': {'tests': ['a11yr', 18:11:57 INFO - 'ts_paint', 18:11:57 INFO - 'tpaint', 18:11:57 INFO - 'sessionrestore', 18:11:57 INFO - 'sessionrestore_no_auto_restore']}, 18:11:57 INFO - 'other-e10s': {'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['a11yr', 18:11:57 INFO - 'ts_paint', 18:11:57 INFO - 'tpaint', 18:11:57 INFO - 'sessionrestore', 18:11:57 INFO - 'sessionrestore_no_auto_restore']}, 18:11:57 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['a11yr', 18:11:57 INFO - 'ts_paint', 18:11:57 INFO - 'tpaint', 18:11:57 INFO - 'sessionrestore', 18:11:57 INFO - 'sessionrestore_no_auto_restore']}, 18:11:57 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['a11yr', 18:11:57 INFO - 'ts_paint', 18:11:57 INFO - 'tpaint', 18:11:57 INFO - 'sessionrestore', 18:11:57 INFO - 'sessionrestore_no_auto_restore']}, 18:11:57 INFO - 'other_l64': {'tests': ['a11yr', 18:11:57 INFO - 'ts_paint', 18:11:57 INFO - 'tpaint', 18:11:57 INFO - 'sessionrestore', 18:11:57 INFO - 'sessionrestore_no_auto_restore']}, 18:11:57 INFO - 'other_nol64': {'tests': ['a11yr', 18:11:57 INFO - 'ts_paint', 18:11:57 INFO - 'tpaint', 18:11:57 INFO - 'sessionrestore', 18:11:57 INFO - 'sessionrestore_no_auto_restore']}, 18:11:57 INFO - 'svgr': {'tests': ['tsvgx', 18:11:57 INFO - 'tsvgr_opacity', 18:11:57 INFO - 'tart', 18:11:57 INFO - 'tscrollx', 18:11:57 INFO - 'cart']}, 18:11:57 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['tsvgx', 18:11:57 INFO - 'tsvgr_opacity', 18:11:57 INFO - 'tart', 18:11:57 INFO - 'tscrollx', 18:11:57 INFO - 'cart']}, 18:11:57 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'tests': ['tp5o']}, 18:11:57 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'talos_options': ['--e10s'], 18:11:57 INFO - 'tests': ['tp5o']}, 18:11:57 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'talos_options': ['--xperf_path', 18:11:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:57 INFO - 'tests': ['tp5n']}, 18:11:57 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:11:57 INFO - 'talos_options': ['--e10s', 18:11:57 INFO - '--xperf_path', 18:11:57 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:11:57 INFO - 'tests': ['tp5n']}}, 18:11:57 INFO - 'talos.zip': {'path': '', 18:11:57 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:11:57 INFO - Running post-action listener: _resource_record_post_action 18:11:57 INFO - ##### 18:11:57 INFO - ##### Running create-virtualenv step. 18:11:57 INFO - ##### 18:11:57 INFO - Running pre-action listener: _resource_record_pre_action 18:11:57 INFO - Running main action method: create_virtualenv 18:11:57 INFO - Creating virtualenv /builds/slave/test/build/venv 18:11:57 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 18:11:57 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:11:57 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:11:57 INFO - Using real prefix '/usr' 18:11:57 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:11:58 INFO - Installing distribute.............................................................................................................................................................................................done. 18:12:00 INFO - Installing pip.................done. 18:12:00 INFO - Return code: 0 18:12:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:00 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:00 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 18:12:00 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 18:12:00 INFO - Using env: {'DISPLAY': ':0', 18:12:00 INFO - 'HOME': '/home/cltbld', 18:12:00 INFO - 'LANG': 'en_US.UTF-8', 18:12:00 INFO - 'LANGUAGE': 'en_US:en', 18:12:00 INFO - 'LOGNAME': 'cltbld', 18:12:00 INFO - 'MAIL': '/var/mail/cltbld', 18:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:00 INFO - 'MOZ_NO_REMOTE': '1', 18:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:00 INFO - 'NO_EM_RESTART': '1', 18:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:00 INFO - 'PWD': '/builds/slave/test', 18:12:00 INFO - 'SHELL': '/bin/bash', 18:12:00 INFO - 'SHLVL': '1', 18:12:00 INFO - 'TERM': 'linux', 18:12:00 INFO - 'TMOUT': '86400', 18:12:00 INFO - 'USER': 'cltbld', 18:12:00 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:00 INFO - '_': '/tools/buildbot/bin/python'} 18:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:00 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 18:12:00 INFO - Cleaning up... 18:12:00 INFO - Return code: 0 18:12:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:00 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:00 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 18:12:00 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 18:12:00 INFO - Using env: {'DISPLAY': ':0', 18:12:00 INFO - 'HOME': '/home/cltbld', 18:12:00 INFO - 'LANG': 'en_US.UTF-8', 18:12:00 INFO - 'LANGUAGE': 'en_US:en', 18:12:00 INFO - 'LOGNAME': 'cltbld', 18:12:00 INFO - 'MAIL': '/var/mail/cltbld', 18:12:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:00 INFO - 'MOZ_NO_REMOTE': '1', 18:12:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:00 INFO - 'NO_EM_RESTART': '1', 18:12:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:00 INFO - 'PWD': '/builds/slave/test', 18:12:00 INFO - 'SHELL': '/bin/bash', 18:12:00 INFO - 'SHLVL': '1', 18:12:00 INFO - 'TERM': 'linux', 18:12:00 INFO - 'TMOUT': '86400', 18:12:00 INFO - 'USER': 'cltbld', 18:12:00 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:00 INFO - '_': '/tools/buildbot/bin/python'} 18:12:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:00 INFO - Downloading/unpacking psutil>=0.7.1 18:12:00 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 18:12:00 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 18:12:00 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 18:12:00 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 18:12:00 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 18:12:00 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 18:12:02 INFO - Creating supposed download cache at /builds/slave/test/build/venv/cache 18:12:02 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpsutil-3.1.1.tar.gz 18:12:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:02 INFO - Installing collected packages: psutil 18:12:02 INFO - Running setup.py install for psutil 18:12:02 INFO - building 'psutil._psutil_linux' extension 18:12:02 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 18:12:02 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 18:12:02 INFO - building 'psutil._psutil_posix' extension 18:12:02 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 18:12:02 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 18:12:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:12:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:12:02 INFO - Successfully installed psutil 18:12:02 INFO - Cleaning up... 18:12:02 INFO - Return code: 0 18:12:02 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 18:12:02 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')]} 18:12:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:02 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')]} 18:12:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:02 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:02 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 18:12:02 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 18:12:02 INFO - Using env: {'DISPLAY': ':0', 18:12:02 INFO - 'HOME': '/home/cltbld', 18:12:02 INFO - 'LANG': 'en_US.UTF-8', 18:12:02 INFO - 'LANGUAGE': 'en_US:en', 18:12:02 INFO - 'LOGNAME': 'cltbld', 18:12:02 INFO - 'MAIL': '/var/mail/cltbld', 18:12:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:02 INFO - 'MOZ_NO_REMOTE': '1', 18:12:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:02 INFO - 'NO_EM_RESTART': '1', 18:12:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:02 INFO - 'PWD': '/builds/slave/test', 18:12:02 INFO - 'SHELL': '/bin/bash', 18:12:02 INFO - 'SHLVL': '1', 18:12:02 INFO - 'TERM': 'linux', 18:12:02 INFO - 'TMOUT': '86400', 18:12:02 INFO - 'USER': 'cltbld', 18:12:02 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:02 INFO - '_': '/tools/buildbot/bin/python'} 18:12:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:03 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 18:12:03 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 18:12:03 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 18:12:03 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 18:12:03 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 18:12:03 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 18:12:03 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 18:12:04 INFO - Downloading mozsystemmonitor-0.0.tar.gz 18:12:04 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmozsystemmonitor-0.0.tar.gz 18:12:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:12:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 18:12:04 INFO - Installing collected packages: mozsystemmonitor 18:12:04 INFO - Running setup.py install for mozsystemmonitor 18:12:05 INFO - Successfully installed mozsystemmonitor 18:12:05 INFO - Cleaning up... 18:12:05 INFO - Return code: 0 18:12:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:12:05 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')]} 18:12:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:05 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')]} 18:12:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:05 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:05 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 18:12:05 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 18:12:05 INFO - Using env: {'DISPLAY': ':0', 18:12:05 INFO - 'HOME': '/home/cltbld', 18:12:05 INFO - 'LANG': 'en_US.UTF-8', 18:12:05 INFO - 'LANGUAGE': 'en_US:en', 18:12:05 INFO - 'LOGNAME': 'cltbld', 18:12:05 INFO - 'MAIL': '/var/mail/cltbld', 18:12:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:05 INFO - 'MOZ_NO_REMOTE': '1', 18:12:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:05 INFO - 'NO_EM_RESTART': '1', 18:12:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:05 INFO - 'PWD': '/builds/slave/test', 18:12:05 INFO - 'SHELL': '/bin/bash', 18:12:05 INFO - 'SHLVL': '1', 18:12:05 INFO - 'TERM': 'linux', 18:12:05 INFO - 'TMOUT': '86400', 18:12:05 INFO - 'USER': 'cltbld', 18:12:05 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:05 INFO - '_': '/tools/buildbot/bin/python'} 18:12:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:05 INFO - Downloading/unpacking blobuploader==1.2.4 18:12:05 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 18:12:05 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 18:12:05 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 18:12:05 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 18:12:05 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 18:12:05 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 18:12:06 INFO - Downloading blobuploader-1.2.4.tar.gz 18:12:06 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fblobuploader-1.2.4.tar.gz 18:12:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:12:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Frequests-1.2.3.tar.gz 18:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:12:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 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 18:12:07 INFO - Downloading docopt-0.6.1.tar.gz 18:12:07 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fdocopt-0.6.1.tar.gz 18:12:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:12:07 INFO - Installing collected packages: blobuploader, requests, docopt 18:12:07 INFO - Running setup.py install for blobuploader 18:12:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:12:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:12:07 INFO - Running setup.py install for requests 18:12:08 INFO - Running setup.py install for docopt 18:12:08 INFO - Successfully installed blobuploader requests docopt 18:12:08 INFO - Cleaning up... 18:12:08 INFO - Return code: 0 18:12:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12: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')]} 18:12:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12: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')]} 18:12:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:08 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:08 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 18:12:08 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 18:12:08 INFO - Using env: {'DISPLAY': ':0', 18:12:08 INFO - 'HOME': '/home/cltbld', 18:12:08 INFO - 'LANG': 'en_US.UTF-8', 18:12:08 INFO - 'LANGUAGE': 'en_US:en', 18:12:08 INFO - 'LOGNAME': 'cltbld', 18:12:08 INFO - 'MAIL': '/var/mail/cltbld', 18:12:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:08 INFO - 'MOZ_NO_REMOTE': '1', 18:12:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:08 INFO - 'NO_EM_RESTART': '1', 18:12:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:08 INFO - 'PWD': '/builds/slave/test', 18:12:08 INFO - 'SHELL': '/bin/bash', 18:12:08 INFO - 'SHLVL': '1', 18:12:08 INFO - 'TERM': 'linux', 18:12:08 INFO - 'TMOUT': '86400', 18:12:08 INFO - 'USER': 'cltbld', 18:12:08 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:08 INFO - '_': '/tools/buildbot/bin/python'} 18:12:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:08 INFO - Running setup.py (path:/tmp/pip-RGfwC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:08 INFO - Running setup.py (path:/tmp/pip-JydAfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:08 INFO - Running setup.py (path:/tmp/pip-Oc3pBZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:08 INFO - Running setup.py (path:/tmp/pip-YJF84N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:08 INFO - Running setup.py (path:/tmp/pip-OWojxu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:08 INFO - Running setup.py (path:/tmp/pip-WsCMF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:08 INFO - Running setup.py (path:/tmp/pip-ne3gwA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:08 INFO - Running setup.py (path:/tmp/pip-7LmfAl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:09 INFO - Running setup.py (path:/tmp/pip-_1BEmJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:09 INFO - Running setup.py (path:/tmp/pip-1sAWzP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:09 INFO - Running setup.py (path:/tmp/pip-2RjI_d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:09 INFO - Running setup.py (path:/tmp/pip-GC54qV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:09 INFO - Running setup.py (path:/tmp/pip-8W7nrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:09 INFO - Running setup.py (path:/tmp/pip-63_TUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:09 INFO - Running setup.py (path:/tmp/pip-DWg810-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:09 INFO - Running setup.py (path:/tmp/pip-pmfTqV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:09 INFO - Running setup.py (path:/tmp/pip-Fm9hlF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:12:09 INFO - Running setup.py install for manifestparser 18:12:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:12:09 INFO - Running setup.py install for mozcrash 18:12:09 INFO - Running setup.py install for mozdebug 18:12:09 INFO - Running setup.py install for mozdevice 18:12:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Running setup.py install for mozfile 18:12:10 INFO - Running setup.py install for mozhttpd 18:12:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Running setup.py install for mozinfo 18:12:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Running setup.py install for mozInstall 18:12:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Running setup.py install for mozleak 18:12:10 INFO - Running setup.py install for mozlog 18:12:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Running setup.py install for moznetwork 18:12:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:12:10 INFO - Running setup.py install for mozprocess 18:12:10 INFO - Running setup.py install for mozprofile 18:12:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozrunner 18:12:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Running setup.py install for mozscreenshot 18:12:11 INFO - Running setup.py install for moztest 18:12:11 INFO - Running setup.py install for mozversion 18:12:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:12:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:12:11 INFO - Cleaning up... 18:12:11 INFO - Return code: 0 18:12:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:11 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')]} 18:12:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:11 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')]} 18:12:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:11 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:11 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 18:12:11 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 18:12:11 INFO - Using env: {'DISPLAY': ':0', 18:12:11 INFO - 'HOME': '/home/cltbld', 18:12:11 INFO - 'LANG': 'en_US.UTF-8', 18:12:11 INFO - 'LANGUAGE': 'en_US:en', 18:12:11 INFO - 'LOGNAME': 'cltbld', 18:12:11 INFO - 'MAIL': '/var/mail/cltbld', 18:12:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:11 INFO - 'MOZ_NO_REMOTE': '1', 18:12:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:11 INFO - 'NO_EM_RESTART': '1', 18:12:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:11 INFO - 'PWD': '/builds/slave/test', 18:12:11 INFO - 'SHELL': '/bin/bash', 18:12:11 INFO - 'SHLVL': '1', 18:12:11 INFO - 'TERM': 'linux', 18:12:11 INFO - 'TMOUT': '86400', 18:12:11 INFO - 'USER': 'cltbld', 18:12:11 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:11 INFO - '_': '/tools/buildbot/bin/python'} 18:12:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:12:11 INFO - Running setup.py (path:/tmp/pip-GHlopW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:12:11 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)) 18:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:12:11 INFO - Running setup.py (path:/tmp/pip-mDcVw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:12:11 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)) 18:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:12:11 INFO - Running setup.py (path:/tmp/pip-2ANAdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:12:11 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)) 18:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:12:11 INFO - Running setup.py (path:/tmp/pip-LvxIK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:12:11 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)) 18:12:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:12:11 INFO - Running setup.py (path:/tmp/pip-UxAc76-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:12:12 INFO - Running setup.py (path:/tmp/pip-Fceq4X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:12:12 INFO - Running setup.py (path:/tmp/pip-gJ7o7R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:12:12 INFO - Running setup.py (path:/tmp/pip-pf_6JS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:12:12 INFO - Running setup.py (path:/tmp/pip-mTn3TH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:12:12 INFO - Running setup.py (path:/tmp/pip-XsvzRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:12:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:12:12 INFO - Running setup.py (path:/tmp/pip-rT75Dk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:12:12 INFO - Running setup.py (path:/tmp/pip-WHfq6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:12:12 INFO - Running setup.py (path:/tmp/pip-nrDjN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:12:12 INFO - Running setup.py (path:/tmp/pip-5PfEZY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:12 INFO - Running setup.py (path:/tmp/pip-TvJHd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:12:12 INFO - Running setup.py (path:/tmp/pip-3OiU4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:12:12 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)) 18:12:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:12:12 INFO - Running setup.py (path:/tmp/pip-WQXSZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:12:12 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)) 18:12:12 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)) 18:12:12 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)) 18:12:12 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)) 18:12:12 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)) 18:12:12 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)) 18:12:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:12: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 18:12: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 18:12: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 18:12: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 18:12: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 18:12: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 18:12:14 INFO - Downloading blessings-1.5.1.tar.gz 18:12:14 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 18:12:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:12:14 INFO - Installing collected packages: blessings 18:12:14 INFO - Running setup.py install for blessings 18:12:15 INFO - Successfully installed blessings 18:12:15 INFO - Cleaning up... 18:12:15 INFO - Return code: 0 18:12:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:12:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:15 INFO - Reading from file tmpfile_stdout 18:12:15 INFO - Current package versions: 18:12:15 INFO - argparse == 1.2.1 18:12:15 INFO - blessings == 1.5.1 18:12:15 INFO - blobuploader == 1.2.4 18:12:15 INFO - docopt == 0.6.1 18:12:15 INFO - manifestparser == 1.1 18:12:15 INFO - mozInstall == 1.12 18:12:15 INFO - mozcrash == 0.16 18:12:15 INFO - mozdebug == 0.1 18:12:15 INFO - mozdevice == 0.47 18:12:15 INFO - mozfile == 1.2 18:12:15 INFO - mozhttpd == 0.7 18:12:15 INFO - mozinfo == 0.9 18:12:15 INFO - mozleak == 0.1 18:12:15 INFO - mozlog == 3.1 18:12:15 INFO - moznetwork == 0.27 18:12:15 INFO - mozprocess == 0.22 18:12:15 INFO - mozprofile == 0.27 18:12:15 INFO - mozrunner == 6.11 18:12:15 INFO - mozscreenshot == 0.1 18:12:15 INFO - mozsystemmonitor == 0.0 18:12:15 INFO - moztest == 0.7 18:12:15 INFO - mozversion == 1.4 18:12:15 INFO - psutil == 3.1.1 18:12:15 INFO - requests == 1.2.3 18:12:15 INFO - wsgiref == 0.1.2 18:12:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:12:15 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')]} 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:15 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')]} 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:15 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 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:15 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 18:12:15 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 18:12:15 INFO - Using env: {'DISPLAY': ':0', 18:12:15 INFO - 'HOME': '/home/cltbld', 18:12:15 INFO - 'LANG': 'en_US.UTF-8', 18:12:15 INFO - 'LANGUAGE': 'en_US:en', 18:12:15 INFO - 'LOGNAME': 'cltbld', 18:12:15 INFO - 'MAIL': '/var/mail/cltbld', 18:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:15 INFO - 'MOZ_NO_REMOTE': '1', 18:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:15 INFO - 'NO_EM_RESTART': '1', 18:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:15 INFO - 'PWD': '/builds/slave/test', 18:12:15 INFO - 'SHELL': '/bin/bash', 18:12:15 INFO - 'SHLVL': '1', 18:12:15 INFO - 'TERM': 'linux', 18:12:15 INFO - 'TMOUT': '86400', 18:12:15 INFO - 'USER': 'cltbld', 18:12:15 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:15 INFO - '_': '/tools/buildbot/bin/python'} 18:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 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)) 18:12:15 INFO - Cleaning up... 18:12:15 INFO - Return code: 0 18:12:15 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:12:15 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')]} 18:12:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:15 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')]} 18:12:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:15 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', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:15 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', 'jsonschema'] in /builds/slave/test/build 18:12:15 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 jsonschema 18:12:15 INFO - Using env: {'DISPLAY': ':0', 18:12:15 INFO - 'HOME': '/home/cltbld', 18:12:15 INFO - 'LANG': 'en_US.UTF-8', 18:12:15 INFO - 'LANGUAGE': 'en_US:en', 18:12:15 INFO - 'LOGNAME': 'cltbld', 18:12:15 INFO - 'MAIL': '/var/mail/cltbld', 18:12:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:15 INFO - 'MOZ_NO_REMOTE': '1', 18:12:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:15 INFO - 'NO_EM_RESTART': '1', 18:12:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:15 INFO - 'PWD': '/builds/slave/test', 18:12:15 INFO - 'SHELL': '/bin/bash', 18:12:15 INFO - 'SHLVL': '1', 18:12:15 INFO - 'TERM': 'linux', 18:12:15 INFO - 'TMOUT': '86400', 18:12:15 INFO - 'USER': 'cltbld', 18:12:15 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:15 INFO - '_': '/tools/buildbot/bin/python'} 18:12:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:15 INFO - Downloading/unpacking jsonschema 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:15 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 18:12:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:12:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fjsonschema-2.5.1-py2.py3-none-any.whl 18:12:17 INFO - Downloading/unpacking functools32 (from jsonschema) 18:12:17 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 18:12:17 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 18:12:17 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 18:12:17 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 18:12:17 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 18:12:17 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 18:12:17 INFO - Downloading functools32-3.2.3-2.tar.gz 18:12:17 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Ffunctools32-3.2.3-2.tar.gz 18:12:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:12:17 INFO - warning: no files found matching '*.txt' 18:12:17 INFO - no previously-included directories found matching 'build' 18:12:17 INFO - no previously-included directories found matching 'dist' 18:12:17 INFO - no previously-included directories found matching '.git*' 18:12:17 INFO - Installing collected packages: jsonschema, functools32 18:12:17 INFO - Running setup.py install for functools32 18:12:17 INFO - warning: no files found matching '*.txt' 18:12:17 INFO - no previously-included directories found matching 'build' 18:12:17 INFO - no previously-included directories found matching 'dist' 18:12:17 INFO - no previously-included directories found matching '.git*' 18:12:17 INFO - Successfully installed jsonschema functools32 18:12:17 INFO - Cleaning up... 18:12:17 INFO - Return code: 0 18:12:17 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 18:12:17 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')]} 18:12:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:12:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:17 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')]} 18:12:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:12:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:12:17 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', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8ad62c1f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24ee8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25bc240>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, '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': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', '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 18:12:17 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', 'flake8'] in /builds/slave/test/build 18:12:17 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 flake8 18:12:17 INFO - Using env: {'DISPLAY': ':0', 18:12:17 INFO - 'HOME': '/home/cltbld', 18:12:17 INFO - 'LANG': 'en_US.UTF-8', 18:12:17 INFO - 'LANGUAGE': 'en_US:en', 18:12:17 INFO - 'LOGNAME': 'cltbld', 18:12:17 INFO - 'MAIL': '/var/mail/cltbld', 18:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:17 INFO - 'MOZ_NO_REMOTE': '1', 18:12:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:17 INFO - 'NO_EM_RESTART': '1', 18:12:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:17 INFO - 'PWD': '/builds/slave/test', 18:12:17 INFO - 'SHELL': '/bin/bash', 18:12:17 INFO - 'SHLVL': '1', 18:12:17 INFO - 'TERM': 'linux', 18:12:17 INFO - 'TMOUT': '86400', 18:12:17 INFO - 'USER': 'cltbld', 18:12:17 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:17 INFO - '_': '/tools/buildbot/bin/python'} 18:12:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:12:18 INFO - Downloading/unpacking flake8 18:12: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 18:12: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 18:12: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 18:12: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 18:12: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 18:12: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 18:12:19 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 18:12:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fflake8-2.5.0-py2.py3-none-any.whl 18:12:19 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 18:12:19 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fmccabe-0.3.1-py2.py3-none-any.whl 18:12:19 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 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 18:12:19 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 18:12:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpyflakes-1.0.0-py2.py3-none-any.whl 18:12:20 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 18:12:20 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 18:12:20 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 18:12:20 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 18:12:20 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 18:12:20 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 18:12:20 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 18:12:20 INFO - Storing download in cache at ./venv/cache/http%3A%2F%2Fpypi.pvt.build.mozilla.org%2Fpub%2Fpep8-1.5.7.tar.gz 18:12:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 18:12:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:12:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:12:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:12:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:12:20 INFO - no previously-included directories found matching 'docs/_build' 18:12:20 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 18:12:20 INFO - Running setup.py install for pep8 18:12:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 18:12:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 18:12:20 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 18:12:20 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 18:12:20 INFO - no previously-included directories found matching 'docs/_build' 18:12:20 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 18:12:20 INFO - Successfully installed flake8 mccabe pyflakes pep8 18:12:20 INFO - Cleaning up... 18:12:20 INFO - Return code: 0 18:12:20 INFO - Running post-action listener: _resource_record_post_action 18:12:20 INFO - Running post-action listener: _start_resource_monitoring 18:12:20 INFO - Starting resource monitoring. 18:12:20 INFO - ##### 18:12:20 INFO - ##### Running install step. 18:12:20 INFO - ##### 18:12:20 INFO - Running pre-action listener: _resource_record_pre_action 18:12:20 INFO - Running main action method: install 18:12:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:12:20 INFO - Reading from file tmpfile_stdout 18:12:20 INFO - Detecting whether we're running mozinstall >=1.0... 18:12:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:12:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:12:20 INFO - Reading from file tmpfile_stdout 18:12:20 INFO - Output received: 18:12:20 INFO - Usage: mozinstall [options] installer 18:12:20 INFO - Options: 18:12:20 INFO - -h, --help show this help message and exit 18:12:20 INFO - -d DEST, --destination=DEST 18:12:20 INFO - Directory to install application into. [default: 18:12:20 INFO - "/builds/slave/test"] 18:12:20 INFO - --app=APP Application being installed. [default: firefox] 18:12:20 INFO - mkdir: /builds/slave/test/build/application 18:12: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'] 18:12: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 18:12:35 INFO - Reading from file tmpfile_stdout 18:12:35 INFO - Output received: 18:12:35 INFO - /builds/slave/test/build/application/firefox/firefox 18:12:35 INFO - Running post-action listener: _resource_record_post_action 18:12:35 INFO - ##### 18:12:35 INFO - ##### Running run-tests step. 18:12:35 INFO - ##### 18:12:35 INFO - Running pre-action listener: _resource_record_pre_action 18:12:35 INFO - Running main action method: run_tests 18:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:12:35 INFO - Python 2.7.3 18:12:35 INFO - Return code: 0 18:12:35 INFO - grabbing minidump binary from tooltool 18:12: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')]} 18:12: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 0x264b0b0>, '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 0x264bfb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x264c440>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:12: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 18:12: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 18:12:35 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:12:35 INFO - Return code: 0 18:12:35 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 18:12:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:12:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:12:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 18:12:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:12:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 18:12:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 18:12:36 INFO - Return code: 0 18:12:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost'] in /builds/slave/test/build 18:12:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --debug --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-060 --webServer localhost 18:12:36 INFO - Using env: {'DISPLAY': ':0', 18:12:36 INFO - 'HOME': '/home/cltbld', 18:12:36 INFO - 'LANG': 'en_US.UTF-8', 18:12:36 INFO - 'LANGUAGE': 'en_US:en', 18:12:36 INFO - 'LOGNAME': 'cltbld', 18:12:36 INFO - 'MAIL': '/var/mail/cltbld', 18:12:36 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:12:36 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 18:12:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:12:36 INFO - 'MOZ_NO_REMOTE': '1', 18:12:36 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:12:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 18:12:36 INFO - 'NO_EM_RESTART': '1', 18:12:36 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 18:12:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:12:36 INFO - 'PWD': '/builds/slave/test', 18:12:36 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:12:36 INFO - 'SHELL': '/bin/bash', 18:12:36 INFO - 'SHLVL': '1', 18:12:36 INFO - 'TERM': 'linux', 18:12:36 INFO - 'TMOUT': '86400', 18:12:36 INFO - 'USER': 'cltbld', 18:12:36 INFO - 'XDG_SESSION_COOKIE': '49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567', 18:12:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:12:36 INFO - '_': '/tools/buildbot/bin/python'} 18:12:36 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--debug', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-060', '--webServer', 'localhost'] with output_timeout 3600 18:12:36 INFO - mozversion INFO | application_buildid: 20151212165532 18:12:36 INFO - mozversion INFO | application_changeset: f07e71078bc8991f74c2101944c8f869c77f442a 18:12:36 INFO - mozversion INFO | application_display_name: Nightly 18:12:36 INFO - mozversion INFO | application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:12:36 INFO - mozversion INFO | application_name: Firefox 18:12:36 INFO - mozversion INFO | application_remotingname: firefox 18:12:36 INFO - mozversion INFO | application_repository: https://hg.mozilla.org/mozilla-central 18:12:36 INFO - mozversion INFO | application_vendor: Mozilla 18:12:36 INFO - mozversion INFO | application_version: 45.0a1 18:12:36 INFO - mozversion INFO | platform_buildid: 20151212165532 18:12:36 INFO - mozversion INFO | platform_changeset: f07e71078bc8991f74c2101944c8f869c77f442a 18:12:36 INFO - mozversion INFO | platform_repository: https://hg.mozilla.org/mozilla-central 18:12:36 INFO - mozversion INFO | platform_version: 45.0a1 18:12:36 INFO - 2015-12-12 18:12:36,684 DEBUG : using testdate: 1449972756 18:12:36 INFO - 2015-12-12 18:12:36,684 DEBUG : actual date: 1449972756 18:12:36 INFO - 2015-12-12 18:12:36,693 INFO : starting webserver on 'localhost:35838' 18:12:36 INFO - 2015-12-12 18:12:36,694 INFO : Starting test suite talos-linux64-ix-060.e 18:12:36 INFO - 2015-12-12 18:12:36,694 INFO : Starting test dromaeo_css 18:12:36 INFO - 2015-12-12 18:12:36,694 DEBUG : operating with platform_type : linux_ 18:12:36 INFO - 2015-12-12 18:12:36,695 INFO : Initialising browser for dromaeo_css test... 18:12:36 INFO - 2015-12-12 18:12:36,707 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO1sON2/profile http://localhost:35838/getInfo.html 18:12:41 INFO - 2015-12-12 18:12:41,475 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:12:41 INFO - 2015-12-12 18:12:41,475 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:12:41 INFO - 2015-12-12 18:12:41,475 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:12:41 INFO - 2015-12-12 18:12:41,475 DEBUG : BROWSER_OUTPUT: __metrics 18:12:41 INFO - 2015-12-12 18:12:41,592 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:12:42 INFO - 2015-12-12 18:12:42,520 INFO : Browser initialized. 18:12:42 INFO - 2015-12-12 18:12:42,521 INFO : Running cycle 1/1 for dromaeo_css test... 18:12:42 INFO - 2015-12-12 18:12:42,521 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpO1sON2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:12:54 INFO - 2015-12-12 18:12:54,520 DEBUG : BROWSER_OUTPUT: RSS: Main: 143376384 18:12:54 INFO - 2015-12-12 18:12:54,520 DEBUG : BROWSER_OUTPUT: 18:13:44 INFO - 2015-12-12 18:13:44,164 DEBUG : BROWSER_OUTPUT: 1449972824162 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] 18:15:56 INFO - 2015-12-12 18:15:56,073 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/dromaeo/cssquery-dojo.html (next: http://localhost:35838/tests/dromaeo/cssquery-ext.html) 18:15:57 INFO - 2015-12-12 18:15:57,043 DEBUG : BROWSER_OUTPUT: RSS: Main: 145821696 18:15:57 INFO - 2015-12-12 18:15:57,044 DEBUG : BROWSER_OUTPUT: 18:18:59 INFO - 2015-12-12 18:18:59,046 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/dromaeo/cssquery-ext.html (next: http://localhost:35838/tests/dromaeo/cssquery-jquery.html) 18:18:59 INFO - 2015-12-12 18:18:59,639 DEBUG : BROWSER_OUTPUT: RSS: Main: 145715200 18:18:59 INFO - 2015-12-12 18:18:59,639 DEBUG : BROWSER_OUTPUT: 18:22:01 INFO - 2015-12-12 18:22:01,400 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/dromaeo/cssquery-jquery.html (next: http://localhost:35838/tests/dromaeo/cssquery-mootools.html) 18:22:01 INFO - 2015-12-12 18:22:01,992 DEBUG : BROWSER_OUTPUT: RSS: Main: 145866752 18:22:01 INFO - 2015-12-12 18:22:01,993 DEBUG : BROWSER_OUTPUT: 18:25:03 INFO - 2015-12-12 18:25:03,780 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/dromaeo/cssquery-mootools.html (next: http://localhost:35838/tests/dromaeo/cssquery-prototype.html) 18:25:04 INFO - 2015-12-12 18:25:04,382 DEBUG : BROWSER_OUTPUT: RSS: Main: 143523840 18:25:04 INFO - 2015-12-12 18:25:04,382 DEBUG : BROWSER_OUTPUT: 18:28:07 INFO - 2015-12-12 18:28:07,047 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/dromaeo/cssquery-prototype.html (next: http://localhost:35838/tests/dromaeo/cssquery-yui.html) 18:28:07 INFO - 2015-12-12 18:28:07,668 DEBUG : BROWSER_OUTPUT: RSS: Main: 144207872 18:28:07 INFO - 2015-12-12 18:28:07,668 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,042 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/dromaeo/cssquery-yui.html (next: http://localhost:35838/tests/dromaeo/cssquery-dojo.html) 18:31:10 INFO - 2015-12-12 18:31:10,293 DEBUG : BROWSER_OUTPUT: RSS: Main: 148176896 18:31:10 INFO - 2015-12-12 18:31:10,293 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,294 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:31:10 INFO - 2015-12-12 18:31:10,294 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:31:10 INFO - 2015-12-12 18:31:10,294 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:31:10 INFO - 2015-12-12 18:31:10,294 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:31:10 INFO - 2015-12-12 18:31:10,296 DEBUG : BROWSER_OUTPUT: |0;dojo.html;2652.456246469474;2661.5874539446386;2714.9864250678743;2729.072115480736;2732.0437846753653;4628.1669299526075;4745.430548334196;4770.379850619419;4802.303665968432;4833.081714474251;4639.8376056838015;4735.1240020596215;4742.359781429506;4791.832285869995;4800.207965685667;4368.170047690936;4450.132224216263;4504.797284122223;4508.954910450902;4545.681802273825;4400.031992961543;4441.422615060038;4477.865664030068;4498.572635599629;4547.636189104864;4259.659227261812;4330.588594083557;4356.063446359017;4431.069475410151;4480.551944805526;4449.132419178261;4480.977595112036;4483.193025255453;4492.213862574069;4517.254652982254;4437.2013037653205;4458.08609235106;4460.8661740147845;4467.195904737146;4478.171538265398;4494.865154045384;4597.977010114962;4612.215923293014;4712.246040633482;4721.952780472186;5140.563052140573;5178.508041736029;5183.14478111115;5198.92201616976;5205.27126202332;5144.922826157574;5162.12243918531;5164.612654050924;5179.430262671103;5196.818111366084;4600.539946005406;4705.6000239979985;4716.434027916672;4735.668503204812;4802.639802014863;5252.107141785906;5294.047071527162;5302.734863256822;5306.28365170697;5309.787608495694;4802.73584952824;5010.69935803853;5013.649044566845;5029.673071250357;5064.215046667772;5090.440051594322;5168.664036837594;5229.111051121318;5267.420583735786;5318.308619879391;5143.974280128576;5147.253648220987;5149.845504634792;5154.561862241677;5194.4026436959475;4818.542238487338;4953.628477864175;5101.928594995093;5108.565771909355;5117.155669314596;4186.748795072305;4232.830686772556;4243.363495475642;4296.183725092223;4297.205017071852;3716.4053751399647;3758.437156195149;3763.2285381496727;3795.449659799369;3796.1648437343742;4214.30463973441;4329.415528903558;4351.173277077345;4488;4489.169503641837;5155.252488389163;5187.170052791535;5187.559057480081;5197.506236907488;5201.2198170274005;4470.731756094645;4482.75344856036;4486.192485352668;4515.277555591089;4516.435445569304;4416.470023597189;4444.8444304449185;4551.726896386227;4551.954480455153;4578.175928332866;4915.705057696549;4929.580985616263;4932.950670493321;4947.307376967227;4992.525710057539;5174.6119041071315;5186.481351864783;5186.559142472932;5191.6884986900905;5195.142801437721;4632.629389648888;4751.477337492804;4785.306130611111;4820.903581928272;4851;5074.340335756476;5095.694258344512;5103.260027296095;5111.080005598879;5141.331626888481;4869.1722407190155;5157.561607263425;5163.380394352552;5165.354330708661;5165.922511162411;4882.023595280887;4930.753462326941;4934.851954441372;4990.8752281193265;5024.296598476143;4993.325901003318;5031.119554078095;5050.747462626928;5056.519630635084;5088.0587091388215;4627.421572303462;4812;4938.703677779345;4953.108440480748;4969.676970996874;4950.158473059517;5004.549590536723;5045.8738531537;5052.595792336678;5087.847364579068;4852.466228714909;4945.480724524017;4959.057779021974;4961.50384961501;5005.149124648746 18:31:10 INFO - 2015-12-12 18:31:10,299 DEBUG : BROWSER_OUTPUT: |1;ext.html;538.3378444513248;578.9015867302559;585.2479563760565;586.6743957103808;598.9667823005313;27063.729362706392;35892.10269743254;36315.455268171;36416.45375319369;36589.451158232645;40729.18541629166;41175.79412102943;41250.38124428136;41255.17489650205;41423.58576414235;7978.242067003625;8112.269895709385;8207.056188538334;8230.794230144264;8238.052623948233;7822.139564647884;7911.367090632764;7975.162607926171;7979;7980.481268717544;8021.318187954002;8055.718049868256;8089.838203235931;8111.351091912633;8113.148119447462;7879.054513458393;7925.28672419485;7936.642851071716;7950.880736788953;7957.681692732283;7918.841623167562;8057.959710201411;8062.838743225103;8069.959650201711;8081.7171399001345;37253.627463725286;37377;37734.81132594347;37761.24477510462;37802.05494862623;2856.8571571421344;2869.5408734602365;2986.3280761828632;3043.771717121225;3459.567554055743;28547.857260713772;29434.41131177386;29486.26284342887;29572.55641165384;29592.408151836844;3374.8312584370683;3477.7565570410084;3507.5440192774777;3510.2102027043966;3600.8019558924248;169149;170365;170456;170800;171376;51018.48981510138;51406.22890656643;51484.227736583984;53245.733771330895;54496;69533.3046669537;70612.64693676602;70872.64563677149;70979;71023.2897671027;12057.939710301567;12293.692657683629;12329.938350308192;12331.630051098482;12340.382980850922;9933.751656208507;10004.54979525923;10052.195824334036;10065.748356291006;10086.092251697382;19601.705974410394;20143.697844532344;20269.797302027087;20455.181792728123;20492.38522844317;7565.886511702329;7766;7913.683452661829;7940.483868548526;7981.201879812089;851.6082602003024;901.5312037740339;1051.2851261142343;1051.4269723000982;1052.9262951593314;26448.8677556611;26856.731432685814;26890.193294201206;26970.730292696822;30280.394392112034;921.8893732752113;944.0795224656043;959.4770849886828;959.7840485890619;1022.8465730140539;12914.806277905838;13215.14101583394;13252.469901203844;13305.534306299232;16027.958182718086;294448;297824;298692;299226;299933;48911.51088489141;49595.75202123966;49617;49688.25467617989;49715.75142124339;1214.5141943222782;1214.6173955204083;1221.645722740413;1223.3516236394726;1224.4612370556927;27660.446791063663;27759.572608833587;27909.720902790712;28401.28996775097;28494.57258141088;41335;41891.162176755686;41891.7905410471;41948.3707744378;42045.78977105095;245674;245679;247073;248008;248266;30187.547186792654;39303.60696393;40612;40704.98237544085;40762;37127.44308835424;38321.80839095786;38638;39103.80448097855;39253.2149357017;33619.663803361655;34104.829475852464;34239;34434.31131377409;34835.47746783851;33731.662683373834;34049;34355;34607.4808877862;38884.2223155541;33025;33148;33493.33013339671;35442;36408.63591364052;28632.713672863003;30347;31008.844955775076;31267.37465250637;31286.217844554067;23918.262077174746;24010;24098.03607855736;24155.275341739776;24313.270601881974 18:31:10 INFO - 2015-12-12 18:31:10,301 DEBUG : BROWSER_OUTPUT: |2;jquery.html;333.8881474705972;337.4785955698446;338.9508521264418;339.6213222257183;340.1496259351621;15131.697366052686;15144.94202319073;15892.125933073676;16034.278457469412;16565;10121.291509594332;10378.896211037887;13927.164370137798;15280.541583752478;15302.693946121071;4585.862424127282;4611;4705.952940470603;4708.0819240248165;4721.055788842228;4635.768211589407;4663.7668116594205;4709.835155769549;4710.670253082286;4716.103940251342;3499.562554680665;3598.1904071583945;4683.274092515666;4691.953080469186;5226.111561034614;5302.814401495929;5355.651882627636;5370.650907690988;5378.623496355237;5393.622446428752;4656.185167595657;4810.39870016248;4816.205326121186;5141.460146684582;5179.507946745053;11100.888991110147;11444.256123351955;11589.478473468629;18108.818911810977;18111;1806.2865168258595;1818.6544556534348;1850.2506484873647;1897.9845782506432;1967.9864869592172;6990.7553235637015;8845.469271843645;8891.599878005505;8919.10808919103;9027.232685221765;1751.7635119258864;1771.7785276840395;1803.774528183977;1807.9728804067818;1818.7999320074782;52519.212211816855;55317.723411383085;55553.72223138899;56131.71934140303;56236.43763562394;8886.955565222133;9049.81900361989;9244.075592440702;9256.49089300095;9265.397749146285;11340.20618556693;11357.20499565039;11374.94312528432;11378.089752819755;11385.03227225695;6517.706703198368;6540.182477190351;6582.407583317525;6630.9336906630315;6871.622060786704;6796.490263230277;6825.2833452486775;6834.2482326944;6843.281455447106;6858.451323894176;3859.9421008684894;4088;4108.568600296986;4123.072308730571;4175.039740859619;1607.0759313394844;1627.9430219942244;1638.7787648667277;1652.1986836384344;1656.155360766018;372.7409450431978;374.5842115252068;376.06735296464376;886.7561420609384;901.4681338010605;9116.908830911738;9123.863142052875;9274.582643780912;9304.8604270936;9356.532173391302;382.8927900187952;383.26987089594365;387.41113507469055;398.4501388084425;411.5657980830253;4681.859544213679;5054.444011158769;5062.898742025212;5107.668001579886;5195.506426889439;469797;477016;477550;479740;503430;72520;73802.63098684579;74011;74340.62829685817;74653;444.8509749234043;448.27603420475697;448.9830533840853;452.20186371055115;452.2492662180764;9461.716148515556;9547.188488978514;9547.283953703361;9667.661631842851;9704.6118155273;30597.694023059485;31056.84471577718;31706.841465792524;32613.18467038248;33190.33619327648;36911;39301.80349098236;39994.60005400025;41842.163156737304;45275.77362113168;8538.530380828866;8766.211041006085;9206.125418085272;9215.387307221075;9252.676156334495;10505.422201778976;10662.253642244968;14176.362063706953;15854.7621785671;16223.432179873647;13629.250391228577;13733.313334333443;13821.239831809347;13837.238951857744;13979.231142287272;12277.570285039981;12964.740705185655;13019.869801301866;13400.32998350098;13702.10936289138;13535.390907409386;13836.654083647989;14314.498992535211;14655.413783448968;14667.85332146665;9014.729558113268;9021.962474315274;9061.864072038792;9111.954440227757;9115;9695.66065187715;9753.12221900004;11377.488013039267;13763.449462021808;14345.426182952566 18:31:10 INFO - 2015-12-12 18:31:10,304 DEBUG : BROWSER_OUTPUT: |3;mootools.html;1495.1851241073628;1597.9041257524545;1677.320685122525;1703.3101593854485;1705.8805883588154;5926.1110833374905;6025;6712.261651218362;7063.0464887240205;7065.187503437106;6779.423748981342;6940.965295173517;7000.544964577299;7002.054722612445;7011.439084873211;2022.5449273913398;2031.908564114611;2041.9387418377473;2049.825764809993;2050.2721533855492;2065.4733043073993;2069.9896500517475;2089.4880754215237;2110.124298416163;2111.292716939827;2011.7284166637462;2028;2039.8164165225128;2067.6795096760025;2071.2129390831537;2094.539201375695;2116.7883211678786;2119.3853782403085;2119.512512122205;2120.289702949514;2073.751149862026;2112.039022244875;2114.2600089968387;2118.269197127004;2122.0875023739786;6959.826004349932;7001.404880585156;7012.158540975065;7022.92977070233;7025.578465292099;4012.057166565855;4059.985003749063;4105.137921036586;4117.608827161415;4117.835286588563;2568.2808813532242;2589.9870500647376;2627.2512333984914;2698.0691661376795;2703;823.5717426938019;828.0477450931417;839.8026463781007;844.0757370679158;859.497194141428;9472.57373418191;9472.715818525387;9475.194608458289;9478.2417406608;9497.857532137023;3770.5852356240794;3785.7917814520465;3789.6210378962433;3812.390017597171;3853.9614603854166;8291.170882911661;8298.294644955125;8298.875516867252;8318.292945099734;8348.582570871433;4213.97893010533;4225.492940847056;4226;4229.957700422957;4249.617534421917;3158.2262345724985;3237.7409807215367;3735.1782607826235;3820.4842346283453;3830.0999265172663;3389.4576867701544;3396.388650043016;3446.3451944130534;3455.0844026333043;3463.3765922133753;1066.461436974323;1081.7998670245872;1084.5499117866154;1084.739662480999;1095.2169199022737;1011.544804837826;1019.1031891935049;1019.2152042926905;1028.141501845952;1035.9067683908333;6756.560823546453;6761.898571521431;6766.357196066366;6814.659267036628;6830.282820303896;409.2245195354786;410.91781643671385;418.5500586869128;420.20790809324643;420.4051267456576;5079.644424890223;5084.77118529667;5088.618353623493;5127.66670166438;5141.511556402136;8259.174082591813;8398;8486.236238738544;8595.5702214889;8691.521966291788;8489.490630562186;8491.915080849112;8522.977242730913;8616.698415555426;8618.439801413013;6549.181352330958;6730.798076057726;6772.492063095287;6818.795436136924;6825.146856642919;6846.965765171142;6847.554908930903;6907.6891539879825;6920.377166055079;6920.826979325557;7665.118511371264;7719.343855772097;7762.68949242024;7769.7669069928;7879.606019699107;7728.7681369559;7968.203179681986;7980.241877021906;8087.676492940452;8110.756677299691;5389.973050134724;5674.375818659862;6126.387361263838;6308.968455157695;6385.169927909342;6430.935690643034;6606.966965165336;6644.501662375438;6658.900116498159;6668.233153187251;6317.652529110759;6362.268339141058;6442;6569.146011018537;6612.801615951529;5518.862028449321;5624.100143976944;5787.392323806108;6248.218972628421;6488.870222595428;6256.7810126645345;6300.873982520232;6302.464290535172;6302.621842689453;6309.842253943688;6017.097435384728;6035.336113027475;6064.909026364519;6066.908996365147;6084.087386892002;5845.444682755132;5929.584929054925;5970.223870896755;5983.970080149571;5990.071538911476 18:31:10 INFO - 2015-12-12 18:31:10,304 DEBUG : BROWSER_OUTPUT: |4;prototype.html;294.543457640657;296.5270393722013;301.966783653798;304.9130997665665;308.74837007838596;5641.5204707599905;5673.290838645169;5684.062129748591;5705.429457054298;5865.061590145578;5648.491635752782;5699.74351154198;5737.082066869291;5744.626599271044;5749.712514374285;4198.6641068714425;4309.913801723964;4319.956800431987;4321.438213032301;4323.4379530661045;4299.634531064848;4306.160298741746;4323.2218200723855;4334.97832510837;4340.891477713051;4290.935635965463;4293.205756934977;4297.162053399588;4306.655467562588;4310.836074259948;4293.420388247578;4318.395424640553;4324.351347297899;4327.199468098381;4333;3735.7384983051197;3737.6449237322413;3744;3746.7002639788752;3746.9625303746884;5238.81664141757;5282.234076058989;5356.732163391854;5439.211314359435;5443.537299329561;4915.778789954425;4948.826791062332;4950.67820591664;4952.87617809554;4955.554000139969;5962.672053037081;5999.400059994009;5999.610025348338;6001.7299221534695;6005.579609427342;3894.2600905827803;4046.959530404717;4047.2107938951917;4051.311277082903;4055.8580449684414;6265.87468250637;6318.71565779541;6319.58922670025;6324.7470101195895;6332.620042797447;4148.108156746314;4163.91672166555;4171.165766846643;4176.644985176294;4184.184084103632;4217.451731274976;4234.34367673005;4236.4492615959725;4238.19474299882;4242.7242229255;4742.099001189763;4922.630802689741;4923.876903077452;4925.532074452921;4926.458089610212;5987.940120598825;6014.729337179838;6015.608985415934;6017.247844019497;6038.607490513103;1467.757819959716;1489.8361180270163;1494.75336569467;1497.4758834408087;1499.0481044536798;990.2424645146468;991.8859331177007;993.6522217224058;993.9602415903428;1005.331454582709;5243.2659427680155;5245.842624721265;5258.789648414097;5258.8948221036135;5267.209918512254;6127.479164271087;6162.26052873658;6193.225846769154;6217.906731398942;6224.844378890564;5213.843584692465;5226.529612334908;5229.895402092013;5235.267062611238;5236.842894713165;3299.5875515560556;3348.5981682198294;3385.2044769479644;3388.0344101931323;3401.9794061781367;5740.1102829061565;5872.970635146882;5914.290285165807;5940.435658612425;5949.25634295713;6448.290688024314;6457.418832305115;6463.418292353711;6473.320301368382;6476.870462590722;5850.239468869105;5878.853028674317;5880.323762767251;5887.087501437284;5900.586958912835;6183.412575805291;6223.408776166257;6261.906071408841;6277.591956522811;6295.96852015737;6083.969580152071;6103.11504831788;6108.08378743192;6118.47992920589;6126.877462450815;5872.588918775645;5891.58758886891;5896.616719913192;5897.941020589911;5898.8230353089475;4004.4794176758;4025.376066709665;4037.798110094425;4043.251998380309;4044.8988775280854;6084;6206.727620837808;6282.780102696383;6285.214348206474;6287.245530536365;6007.429294217042;6030.03519436888;6030.969845150747;6033.9698301508215;6040.063790112364;5992.790252341147;6010.579259451796;6020.277566692025;6020.789272375621;6027.758889644365;5627.099664053731;5843.094320380348;5854.297484301911;5892.351841297368;5899.970500147472;6078.300995385411;6093.2078829751845;6103.572749907464;6113.938860611337;6115.4190351916495;5937.465628093492;5940.46535811779;5947.702614869326;5950.910736339045;5960.433758792908 18:31:10 INFO - 2015-12-12 18:31:10,305 DEBUG : BROWSER_OUTPUT: |5;yui.html;669.4276393683401;790.3242727468015;790.6521130702492;791.5211297165218;804.21588950773;11109.666709998693;11271.041961433268;11286.379249141293;11296.491657875406;11337.716557086076;11823.467943942522;11829.822552661695;11859.229150105237;11865.644030679072;11879.227850189753;795.1372760554791;798.4530596541388;803.5098589860181;805.9032916050082;809.6882700160422;623.2925629410629;626.4862812493757;777.2810150610662;796.0845028217567;803.3854101612242;776.7397921696239;831.418007394823;839.9832003359929;843.7089204224533;847.3560094328324;827.1067247372823;828.5691440450962;832.0763952013231;833.7582101190648;835.482001159282;616.6947361056306;617.3918690090258;622.8598565322811;623.2085251730323;624.9062640603901;10166.440845753481;10201.387916724945;10278.486075696186;10281.331713438603;10313.793724125551;10791.83812242817;11357.659270221906;11613.129015323884;11648.417579121011;12014.339211343373;9368.53157342137;9379.76550586234;9463.574139163755;9477.47873866937;9532.90467095334;3346.8159251241173;3352.1619595101224;3364.747643926715;3393.100828280508;3395;39769;39792.60207397918;40099.19801603981;40270;40301.19397612061;9209.861852072225;9394.953025234967;9433;9466.479343636167;9485.762855928519;15874.841251587337;15884.92057539705;15957.760633590506;15971.121588312755;15993;6140.969295153495;6183.783567575113;6260.561760676709;6270.811875643738;6346.777862774781;3522.947155792665;3524.2070534129616;3569.37535931211;3598.694111000542;3660.853565857336;2695.9191224263304;2703.324168957761;2735.288824905499;2736.5758307462374;2739.4384151248946;464.76993888025646;472.8179650834385;483.43921051579997;484.8085006422443;491.08902984963095;499.48303505871246;506.25327641728154;577.2985822226016;579.325086274494;596.7911231069178;7622;7914.287714105758;8237.917620823715;8315.29320007794;8408.663653453794;497.7959036794897;509.7012473743444;607.7963882099458;608;612.948411003268;3388;3641.6904563111902;3676.4485327201132;3683.613220611851;3736.23407201523;48008;48107.27839082417;48142.27786583204;48810.755946220044;51481.742591286806;44825.32762008572;45091.323630145576;45279;45770.31344529835;46994.76502617465;39797;40769.18461630751;41166.588334116874;41268.58731412707;42647.786761065996;7745.148033716174;7858.6070696466095;7878.4091193161885;8039.397045221749;8336.541490217855;30232.84883575568;30256;31021.379572408878;31140;31715.84142079275;38791.22417551577;39243.60756392399;39351;39485.01287467836;39693.2061358777;10736.62421815264;10774.24580279373;10791.298565593212;11078.169137314573;11140.220184587;11267.26762760418;11401.20191586581;11455.885441145481;11557.364344961108;11593.826092608786;10594.2584019121;10660.573577057141;10668.46657667098;10720.249582529148;10810.243282970117;10854.34873907568;11242.887571124184;11472.483738231633;11643;11671.12466565028;11034.337939723642;11078.446077696244;11093.50079246452;11155.330680159217;11462.94268528652;9033.457992520469;9103.772405689911;9239.260859131387;9392.95303523478;9696.806063878541;8552.273056789994;8692.261157801404;8967.730968070968;9428.387154834914;9471.194948429185 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,330 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: __startTimestamp1449973870293__endTimestamp 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:31:10 INFO - 2015-12-12 18:31:10,306 DEBUG : BROWSER_OUTPUT: Number of tests: 6 18:31:10 INFO - 2015-12-12 18:31:10,307 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,307 DEBUG : BROWSER_OUTPUT: [#0] dojo.html Cycles:165 Average:4737.57 Median:4833.08 stddev:505.26 (10.5%) stddev-sans-first:479.60 18:31:10 INFO - 2015-12-12 18:31:10,307 DEBUG : BROWSER_OUTPUT: Values: 2652.5 2661.6 2715.0 2729.1 2732.0 4628.2 4745.4 4770.4 4802.3 4833.1 4639.8 4735.1 4742.4 4791.8 4800.2 4368.2 4450.1 4504.8 4509.0 4545.7 4400.0 4441.4 4477.9 4498.6 4547.6 4259.7 4330.6 4356.1 4431.1 4480.6 4449.1 4481.0 4483.2 4492.2 4517.3 4437.2 4458.1 4460.9 4467.2 4478.2 4494.9 4598.0 4612.2 4712.2 4722.0 5140.6 5178.5 5183.1 5198.9 5205.3 5144.9 5162.1 5164.6 5179.4 5196.8 4600.5 4705.6 4716.4 4735.7 4802.6 5252.1 5294.0 5302.7 5306.3 5309.8 4802.7 5010.7 5013.6 5029.7 5064.2 5090.4 5168.7 5229.1 5267.4 5318.3 5144.0 5147.3 5149.8 5154.6 5194.4 4818.5 4953.6 5101.9 5108.6 5117.2 4186.7 4232.8 4243.4 4296.2 4297.2 3716.4 3758.4 3763.2 3795.4 3796.2 4214.3 4329.4 4351.2 4488.0 4489.2 5155.3 5187.2 5187.6 5197.5 5201.2 4470.7 4482.8 4486.2 4515.3 4516.4 4416.5 4444.8 4551.7 4552.0 4578.2 4915.7 4929.6 4933.0 4947.3 4992.5 5174.6 5186.5 5186.6 5191.7 5195.1 4632.6 4751.5 4785.3 4820.9 4851.0 5074.3 5095.7 5103.3 5111.1 5141.3 4869.2 5157.6 5163.4 5165.4 5165.9 4882.0 4930.8 4934.9 4990.9 5024.3 4993.3 5031.1 5050.7 5056.5 5088.1 4627.4 4812.0 4938.7 4953.1 4969.7 4950.2 5004.5 5045.9 5052.6 5087.8 4852.5 4945.5 4959.1 4961.5 5005.1 18:31:10 INFO - 2015-12-12 18:31:10,307 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,307 DEBUG : BROWSER_OUTPUT: [#1] ext.html Cycles:180 Average:41035.86 Median:19476.11 stddev:63902.94 (328.1%) stddev-sans-first:64009.86 18:31:10 INFO - 2015-12-12 18:31:10,308 DEBUG : BROWSER_OUTPUT: Values: 538.3 578.9 585.2 586.7 599.0 27063.7 35892.1 36315.5 36416.5 36589.5 40729.2 41175.8 41250.4 41255.2 41423.6 7978.2 8112.3 8207.1 8230.8 8238.1 7822.1 7911.4 7975.2 7979.0 7980.5 8021.3 8055.7 8089.8 8111.4 8113.1 7879.1 7925.3 7936.6 7950.9 7957.7 7918.8 8058.0 8062.8 8070.0 8081.7 37253.6 37377.0 37734.8 37761.2 37802.1 2856.9 2869.5 2986.3 3043.8 3459.6 28547.9 29434.4 29486.3 29572.6 29592.4 3374.8 3477.8 3507.5 3510.2 3600.8 169149.0 170365.0 170456.0 170800.0 171376.0 51018.5 51406.2 51484.2 53245.7 54496.0 69533.3 70612.6 70872.6 70979.0 71023.3 12057.9 12293.7 12329.9 12331.6 12340.4 9933.8 10004.5 10052.2 10065.7 10086.1 19601.7 20143.7 20269.8 20455.2 20492.4 7565.9 7766.0 7913.7 7940.5 7981.2 851.6 901.5 1051.3 1051.4 1052.9 26448.9 26856.7 26890.2 26970.7 30280.4 921.9 944.1 959.5 959.8 1022.8 12914.8 13215.1 13252.5 13305.5 16028.0 294448.0 297824.0 298692.0 299226.0 299933.0 48911.5 49595.8 49617.0 49688.3 49715.8 1214.5 1214.6 1221.6 1223.4 1224.5 27660.4 27759.6 27909.7 28401.3 28494.6 41335.0 41891.2 41891.8 41948.4 42045.8 245674.0 245679.0 247073.0 248008.0 248266.0 30187.5 39303.6 40612.0 40705.0 40762.0 37127.4 38321.8 38638.0 39103.8 39253.2 33619.7 34104.8 34239.0 34434.3 34835.5 33731.7 34049.0 34355.0 34607.5 38884.2 33025.0 33148.0 33493.3 35442.0 36408.6 28632.7 30347.0 31008.8 31267.4 31286.2 23918.3 24010.0 24098.0 24155.3 24313.3 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: [#2] jquery.html Cycles:180 Average:25325.09 Median:452.23 stddev:78831.93 (17432.0%) stddev-sans-first:79030.74 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: Values: 333.9 337.5 339.0 339.6 340.1 15131.7 15144.9 15892.1 16034.3 16565.0 10121.3 10378.9 13927.2 15280.5 15302.7 4585.9 4611.0 4706.0 4708.1 4721.1 4635.8 4663.8 4709.8 4710.7 4716.1 3499.6 3598.2 4683.3 4692.0 5226.1 5302.8 5355.7 5370.7 5378.6 5393.6 4656.2 4810.4 4816.2 5141.5 5179.5 11100.9 11444.3 11589.5 18108.8 18111.0 1806.3 1818.7 1850.3 1898.0 1968.0 6990.8 8845.5 8891.6 8919.1 9027.2 1751.8 1771.8 1803.8 1808.0 1818.8 52519.2 55317.7 55553.7 56131.7 56236.4 8887.0 9049.8 9244.1 9256.5 9265.4 11340.2 11357.2 11374.9 11378.1 11385.0 6517.7 6540.2 6582.4 6630.9 6871.6 6796.5 6825.3 6834.2 6843.3 6858.5 3859.9 4088.0 4108.6 4123.1 4175.0 1607.1 1627.9 1638.8 1652.2 1656.2 372.7 374.6 376.1 886.8 901.5 9116.9 9123.9 9274.6 9304.9 9356.5 382.9 383.3 387.4 398.5 411.6 4681.9 5054.4 5062.9 5107.7 5195.5 469797.0 477016.0 477550.0 479740.0 503430.0 72520.0 73802.6 74011.0 74340.6 74653.0 444.9 448.3 449.0 452.2 452.2 9461.7 9547.2 9547.3 9667.7 9704.6 30597.7 31056.8 31706.8 32613.2 33190.3 36911.0 39301.8 39994.6 41842.2 45275.8 8538.5 8766.2 9206.1 9215.4 9252.7 10505.4 10662.3 14176.4 15854.8 16223.4 13629.3 13733.3 13821.2 13837.2 13979.2 12277.6 12964.7 13019.9 13400.3 13702.1 13535.4 13836.7 14314.5 14655.4 14667.9 9014.7 9022.0 9061.9 9112.0 9115.0 9695.7 9753.1 11377.5 13763.4 14345.4 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: [#3] mootools.html Cycles:180 Average:4916.12 Median:6003.58 stddev:2586.06 (43.1%) stddev-sans-first:2580.54 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: Values: 1495.2 1597.9 1677.3 1703.3 1705.9 5926.1 6025.0 6712.3 7063.0 7065.2 6779.4 6941.0 7000.5 7002.1 7011.4 2022.5 2031.9 2041.9 2049.8 2050.3 2065.5 2070.0 2089.5 2110.1 2111.3 2011.7 2028.0 2039.8 2067.7 2071.2 2094.5 2116.8 2119.4 2119.5 2120.3 2073.8 2112.0 2114.3 2118.3 2122.1 6959.8 7001.4 7012.2 7022.9 7025.6 4012.1 4060.0 4105.1 4117.6 4117.8 2568.3 2590.0 2627.3 2698.1 2703.0 823.6 828.0 839.8 844.1 859.5 9472.6 9472.7 9475.2 9478.2 9497.9 3770.6 3785.8 3789.6 3812.4 3854.0 8291.2 8298.3 8298.9 8318.3 8348.6 4214.0 4225.5 4226.0 4230.0 4249.6 3158.2 3237.7 3735.2 3820.5 3830.1 3389.5 3396.4 3446.3 3455.1 3463.4 1066.5 1081.8 1084.5 1084.7 1095.2 1011.5 1019.1 1019.2 1028.1 1035.9 6756.6 6761.9 6766.4 6814.7 6830.3 409.2 410.9 418.6 420.2 420.4 5079.6 5084.8 5088.6 5127.7 5141.5 8259.2 8398.0 8486.2 8595.6 8691.5 8489.5 8491.9 8523.0 8616.7 8618.4 6549.2 6730.8 6772.5 6818.8 6825.1 6847.0 6847.6 6907.7 6920.4 6920.8 7665.1 7719.3 7762.7 7769.8 7879.6 7728.8 7968.2 7980.2 8087.7 8110.8 5390.0 5674.4 6126.4 6309.0 6385.2 6430.9 6607.0 6644.5 6658.9 6668.2 6317.7 6362.3 6442.0 6569.1 6612.8 5518.9 5624.1 5787.4 6248.2 6488.9 6256.8 6300.9 6302.5 6302.6 6309.8 6017.1 6035.3 6064.9 6066.9 6084.1 5845.4 5929.6 5970.2 5984.0 5990.1 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,309 DEBUG : BROWSER_OUTPUT: [#4] prototype.html Cycles:180 Average:4938.98 Median:5691.90 stddev:1492.95 (26.2%) stddev-sans-first:1455.87 18:31:10 INFO - 2015-12-12 18:31:10,311 DEBUG : BROWSER_OUTPUT: Values: 294.5 296.5 302.0 304.9 308.7 5641.5 5673.3 5684.1 5705.4 5865.1 5648.5 5699.7 5737.1 5744.6 5749.7 4198.7 4309.9 4320.0 4321.4 4323.4 4299.6 4306.2 4323.2 4335.0 4340.9 4290.9 4293.2 4297.2 4306.7 4310.8 4293.4 4318.4 4324.4 4327.2 4333.0 3735.7 3737.6 3744.0 3746.7 3747.0 5238.8 5282.2 5356.7 5439.2 5443.5 4915.8 4948.8 4950.7 4952.9 4955.6 5962.7 5999.4 5999.6 6001.7 6005.6 3894.3 4047.0 4047.2 4051.3 4055.9 6265.9 6318.7 6319.6 6324.7 6332.6 4148.1 4163.9 4171.2 4176.6 4184.2 4217.5 4234.3 4236.4 4238.2 4242.7 4742.1 4922.6 4923.9 4925.5 4926.5 5987.9 6014.7 6015.6 6017.2 6038.6 1467.8 1489.8 1494.8 1497.5 1499.0 990.2 991.9 993.7 994.0 1005.3 5243.3 5245.8 5258.8 5258.9 5267.2 6127.5 6162.3 6193.2 6217.9 6224.8 5213.8 5226.5 5229.9 5235.3 5236.8 3299.6 3348.6 3385.2 3388.0 3402.0 5740.1 5873.0 5914.3 5940.4 5949.3 6448.3 6457.4 6463.4 6473.3 6476.9 5850.2 5878.9 5880.3 5887.1 5900.6 6183.4 6223.4 6261.9 6277.6 6296.0 6084.0 6103.1 6108.1 6118.5 6126.9 5872.6 5891.6 5896.6 5897.9 5898.8 4004.5 4025.4 4037.8 4043.3 4044.9 6084.0 6206.7 6282.8 6285.2 6287.2 6007.4 6030.0 6031.0 6034.0 6040.1 5992.8 6010.6 6020.3 6020.8 6027.8 5627.1 5843.1 5854.3 5892.4 5900.0 6078.3 6093.2 6103.6 6113.9 6115.4 5937.5 5940.5 5947.7 5950.9 5960.4 18:31:10 INFO - 2015-12-12 18:31:10,311 DEBUG : BROWSER_OUTPUT: 18:31:10 INFO - 2015-12-12 18:31:10,312 DEBUG : BROWSER_OUTPUT: [#5] yui.html Cycles:180 Average:12254.89 Median:44958.33 stddev:13783.79 (30.7%) stddev-sans-first:13795.00 18:31:10 INFO - 2015-12-12 18:31:10,312 DEBUG : BROWSER_OUTPUT: Values: 669.4 790.3 790.7 791.5 804.2 11109.7 11271.0 11286.4 11296.5 11337.7 11823.5 11829.8 11859.2 11865.6 11879.2 795.1 798.5 803.5 805.9 809.7 623.3 626.5 777.3 796.1 803.4 776.7 831.4 840.0 843.7 847.4 827.1 828.6 832.1 833.8 835.5 616.7 617.4 622.9 623.2 624.9 10166.4 10201.4 10278.5 10281.3 10313.8 10791.8 11357.7 11613.1 11648.4 12014.3 9368.5 9379.8 9463.6 9477.5 9532.9 3346.8 3352.2 3364.7 3393.1 3395.0 39769.0 39792.6 40099.2 40270.0 40301.2 9209.9 9395.0 9433.0 9466.5 9485.8 15874.8 15884.9 15957.8 15971.1 15993.0 6141.0 6183.8 6260.6 6270.8 6346.8 3522.9 3524.2 3569.4 3598.7 3660.9 2695.9 2703.3 2735.3 2736.6 2739.4 464.8 472.8 483.4 484.8 491.1 499.5 506.3 577.3 579.3 596.8 7622.0 7914.3 8237.9 8315.3 8408.7 497.8 509.7 607.8 608.0 612.9 3388.0 3641.7 3676.4 3683.6 3736.2 48008.0 48107.3 48142.3 48810.8 51481.7 44825.3 45091.3 45279.0 45770.3 46994.8 39797.0 40769.2 41166.6 41268.6 42647.8 7745.1 7858.6 7878.4 8039.4 8336.5 30232.8 30256.0 31021.4 31140.0 31715.8 38791.2 39243.6 39351.0 39485.0 39693.2 10736.6 10774.2 10791.3 11078.2 11140.2 11267.3 11401.2 11455.9 11557.4 11593.8 10594.3 10660.6 10668.5 10720.2 10810.2 10854.3 11242.9 11472.5 11643.0 11671.1 11034.3 11078.4 11093.5 11155.3 11462.9 9033.5 9103.8 9239.3 9393.0 9696.8 8552.3 8692.3 8967.7 9428.4 9471.2 18:31:10 INFO - 2015-12-12 18:31:10,312 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:31:10 INFO - 2015-12-12 18:31:10,312 DEBUG : BROWSER_OUTPUT: 18:31:11 INFO - 2015-12-12 18:31:11,052 INFO : Browser exited with error code: 0 18:31:11 INFO - 2015-12-12 18:31:11,069 INFO : Completed test dromaeo_css (00:18:34) 18:31:11 INFO - 2015-12-12 18:31:11,069 INFO : Starting test kraken 18:31:11 INFO - 2015-12-12 18:31:11,069 DEBUG : operating with platform_type : linux_ 18:31:11 INFO - 2015-12-12 18:31:11,069 INFO : Initialising browser for kraken test... 18:31:11 INFO - 2015-12-12 18:31:11,075 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3Q3NB/profile http://localhost:35838/getInfo.html 18:31:15 INFO - 2015-12-12 18:31:15,494 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:31:15 INFO - 2015-12-12 18:31:15,494 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:31:15 INFO - 2015-12-12 18:31:15,494 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:31:15 INFO - 2015-12-12 18:31:15,495 DEBUG : BROWSER_OUTPUT: __metrics 18:31:15 INFO - 2015-12-12 18:31:15,611 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:31:16 INFO - 2015-12-12 18:31:16,484 INFO : Browser initialized. 18:31:16 INFO - 2015-12-12 18:31:16,485 INFO : Running cycle 1/1 for kraken test... 18:31:16 INFO - 2015-12-12 18:31:16,485 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpa3Q3NB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:31:28 INFO - 2015-12-12 18:31:28,495 DEBUG : BROWSER_OUTPUT: RSS: Main: 143273984 18:31:28 INFO - 2015-12-12 18:31:28,495 DEBUG : BROWSER_OUTPUT: 18:31:31 INFO - 2015-12-12 18:31:31,096 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:35838/tests/kraken/driver.html?testName=audio-beat-detection) 18:31:31 INFO - 2015-12-12 18:31:31,722 DEBUG : BROWSER_OUTPUT: RSS: Main: 143519744 18:31:31 INFO - 2015-12-12 18:31:31,723 DEBUG : BROWSER_OUTPUT: 18:31:34 INFO - 2015-12-12 18:31:34,492 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:35838/tests/kraken/driver.html?testName=audio-dft) 18:31:35 INFO - 2015-12-12 18:31:35,094 DEBUG : BROWSER_OUTPUT: RSS: Main: 143896576 18:31:35 INFO - 2015-12-12 18:31:35,094 DEBUG : BROWSER_OUTPUT: 18:31:37 INFO - 2015-12-12 18:31:37,998 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:35838/tests/kraken/driver.html?testName=audio-fft) 18:31:38 INFO - 2015-12-12 18:31:38,580 DEBUG : BROWSER_OUTPUT: RSS: Main: 137940992 18:31:38 INFO - 2015-12-12 18:31:38,580 DEBUG : BROWSER_OUTPUT: 18:31:40 INFO - 2015-12-12 18:31:40,712 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:35838/tests/kraken/driver.html?testName=audio-oscillator) 18:31:41 INFO - 2015-12-12 18:31:41,294 DEBUG : BROWSER_OUTPUT: RSS: Main: 138993664 18:31:41 INFO - 2015-12-12 18:31:41,295 DEBUG : BROWSER_OUTPUT: 18:31:43 INFO - 2015-12-12 18:31:43,532 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:35838/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:31:44 INFO - 2015-12-12 18:31:44,117 DEBUG : BROWSER_OUTPUT: RSS: Main: 140152832 18:31:44 INFO - 2015-12-12 18:31:44,118 DEBUG : BROWSER_OUTPUT: 18:31:47 INFO - 2015-12-12 18:31:47,156 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:35838/tests/kraken/driver.html?testName=imaging-darkroom) 18:31:47 INFO - 2015-12-12 18:31:47,742 DEBUG : BROWSER_OUTPUT: RSS: Main: 141557760 18:31:47 INFO - 2015-12-12 18:31:47,743 DEBUG : BROWSER_OUTPUT: 18:31:51 INFO - 2015-12-12 18:31:51,210 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:35838/tests/kraken/driver.html?testName=imaging-desaturate) 18:31:51 INFO - 2015-12-12 18:31:51,820 DEBUG : BROWSER_OUTPUT: RSS: Main: 137695232 18:31:51 INFO - 2015-12-12 18:31:51,820 DEBUG : BROWSER_OUTPUT: 18:31:54 INFO - 2015-12-12 18:31:54,719 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:35838/tests/kraken/driver.html?testName=json-parse-financial) 18:31:55 INFO - 2015-12-12 18:31:55,312 DEBUG : BROWSER_OUTPUT: RSS: Main: 138006528 18:31:55 INFO - 2015-12-12 18:31:55,312 DEBUG : BROWSER_OUTPUT: 18:31:57 INFO - 2015-12-12 18:31:57,107 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:35838/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:31:57 INFO - 2015-12-12 18:31:57,693 DEBUG : BROWSER_OUTPUT: RSS: Main: 140898304 18:31:57 INFO - 2015-12-12 18:31:57,693 DEBUG : BROWSER_OUTPUT: 18:31:59 INFO - 2015-12-12 18:31:59,624 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:32:00 INFO - 2015-12-12 18:32:00,246 DEBUG : BROWSER_OUTPUT: RSS: Main: 140148736 18:32:00 INFO - 2015-12-12 18:32:00,246 DEBUG : BROWSER_OUTPUT: 18:32:02 INFO - 2015-12-12 18:32:02,472 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:32:03 INFO - 2015-12-12 18:32:03,058 DEBUG : BROWSER_OUTPUT: RSS: Main: 140083200 18:32:03 INFO - 2015-12-12 18:32:03,058 DEBUG : BROWSER_OUTPUT: 18:32:05 INFO - 2015-12-12 18:32:05,646 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:32:06 INFO - 2015-12-12 18:32:06,255 DEBUG : BROWSER_OUTPUT: RSS: Main: 140369920 18:32:06 INFO - 2015-12-12 18:32:06,255 DEBUG : BROWSER_OUTPUT: 18:32:08 INFO - 2015-12-12 18:32:08,884 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:32:09 INFO - 2015-12-12 18:32:09,474 DEBUG : BROWSER_OUTPUT: RSS: Main: 141447168 18:32:09 INFO - 2015-12-12 18:32:09,474 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,248 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:35838/tests/kraken/driver.html?testName=ai-astar) 18:32:11 INFO - 2015-12-12 18:32:11,498 DEBUG : BROWSER_OUTPUT: RSS: Main: 140480512 18:32:11 INFO - 2015-12-12 18:32:11,498 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,498 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:32:11 INFO - 2015-12-12 18:32:11,498 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:32:11 INFO - 2015-12-12 18:32:11,498 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |0;ai-astar;113;108;112;113;117;125;112;113;112;115 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |1;audio-beat-detection;158;138;157;168;133;161;155;131;176;134 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |2;audio-dft;164;163;162;162;161;166;169;164;166;165 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |3;audio-fft;73;73;73;74;160;74;77;75;73;159 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |4;audio-oscillator;95;94;128;93;94;95;129;95;96;130 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |5;imaging-gaussian-blur;111;110;109;110;111;110;111;110;110;111 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |6;imaging-darkroom;148;148;147;148;147;149;148;150;148;148 18:32:11 INFO - 2015-12-12 18:32:11,499 DEBUG : BROWSER_OUTPUT: |7;imaging-desaturate;96;96;97;97;98;95;96;97;95;96 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: |8;json-parse-financial;75;75;73;74;76;77;76;74;74;75 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: |9;json-stringify-tinderbox;68;68;68;69;68;74;68;67;68;68 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: |10;stanford-crypto-aes;93;86;83;84;84;86;85;88;96;100 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: |11;stanford-crypto-ccm;130;119;125;126;125;135;133;126;137;125 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: |12;stanford-crypto-pbkdf2;140;150;144;148;150;139;140;153;150;139 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: |13;stanford-crypto-sha256-iterative;71;72;71;71;72;72;69;70;73;72 18:32:11 INFO - 2015-12-12 18:32:11,500 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:32:11 INFO - 2015-12-12 18:32:11,501 DEBUG : BROWSER_OUTPUT: __start_cc_report 18:32:11 INFO - 2015-12-12 18:32:11,501 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_cycle_collect,809 18:32:11 INFO - 2015-12-12 18:32:11,501 DEBUG : BROWSER_OUTPUT: __end_cc_report 18:32:11 INFO - 2015-12-12 18:32:11,501 DEBUG : BROWSER_OUTPUT: __startTimestamp1449973931497__endTimestamp 18:32:11 INFO - 2015-12-12 18:32:11,501 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: Number of tests: 14 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: [#0] ai-astar Cycles:10 Average:114.00 Median:113.00 stddev:4.50 (4.0%) stddev-sans-first:4.76 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: Values: 113.0 108.0 112.0 113.0 117.0 125.0 112.0 113.0 112.0 115.0 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: [#1] audio-beat-detection Cycles:10 Average:151.10 Median:157.50 stddev:15.98 (10.1%) stddev-sans-first:16.75 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: Values: 158.0 138.0 157.0 168.0 133.0 161.0 155.0 131.0 176.0 134.0 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: [#2] audio-dft Cycles:10 Average:164.20 Median:164.50 stddev:2.39 (1.5%) stddev-sans-first:2.54 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: Values: 164.0 163.0 162.0 162.0 161.0 166.0 169.0 164.0 166.0 165.0 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: [#3] audio-fft Cycles:10 Average:91.10 Median:73.50 stddev:36.07 (49.1%) stddev-sans-first:37.66 18:32:11 INFO - 2015-12-12 18:32:11,509 DEBUG : BROWSER_OUTPUT: Values: 73.0 73.0 73.0 74.0 160.0 74.0 77.0 75.0 73.0 159.0 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: [#4] audio-oscillator Cycles:10 Average:104.90 Median:94.50 stddev:16.66 (17.6%) stddev-sans-first:17.28 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: Values: 95.0 94.0 128.0 93.0 94.0 95.0 129.0 95.0 96.0 130.0 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: [#5] imaging-gaussian-blur Cycles:10 Average:110.30 Median:110.50 stddev:0.67 (0.6%) stddev-sans-first:0.67 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: Values: 111.0 110.0 109.0 110.0 111.0 110.0 111.0 110.0 110.0 111.0 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: [#6] imaging-darkroom Cycles:10 Average:148.10 Median:148.00 stddev:0.88 (0.6%) stddev-sans-first:0.93 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: Values: 148.0 148.0 147.0 148.0 147.0 149.0 148.0 150.0 148.0 148.0 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: [#7] imaging-desaturate Cycles:10 Average:96.30 Median:96.50 stddev:0.95 (1.0%) stddev-sans-first:1.00 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: Values: 96.0 96.0 97.0 97.0 98.0 95.0 96.0 97.0 95.0 96.0 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: [#8] json-parse-financial Cycles:10 Average:74.90 Median:75.00 stddev:1.20 (1.6%) stddev-sans-first:1.27 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: Values: 75.0 75.0 73.0 74.0 76.0 77.0 76.0 74.0 74.0 75.0 18:32:11 INFO - 2015-12-12 18:32:11,510 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:68.00 stddev:1.96 (2.9%) stddev-sans-first:2.06 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: Values: 68.0 68.0 68.0 69.0 68.0 74.0 68.0 67.0 68.0 68.0 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: [#10] stanford-crypto-aes Cycles:10 Average:88.50 Median:86.00 stddev:5.82 (6.8%) stddev-sans-first:5.94 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: Values: 93.0 86.0 83.0 84.0 84.0 86.0 85.0 88.0 96.0 100.0 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: [#11] stanford-crypto-ccm Cycles:10 Average:128.10 Median:128.00 stddev:5.53 (4.3%) stddev-sans-first:5.82 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: Values: 130.0 119.0 125.0 126.0 125.0 135.0 133.0 126.0 137.0 125.0 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: [#12] stanford-crypto-pbkdf2 Cycles:10 Average:145.30 Median:149.00 stddev:5.48 (3.7%) stddev-sans-first:5.46 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: Values: 140.0 150.0 144.0 148.0 150.0 139.0 140.0 153.0 150.0 139.0 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.30 Median:72.00 stddev:1.16 (1.6%) stddev-sans-first:1.22 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: Values: 71.0 72.0 71.0 71.0 72.0 72.0 69.0 70.0 73.0 72.0 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:32:11 INFO - 2015-12-12 18:32:11,511 DEBUG : BROWSER_OUTPUT: 18:32:12 INFO - 2015-12-12 18:32:12,452 INFO : Browser exited with error code: 0 18:32:12 INFO - 2015-12-12 18:32:12,504 INFO : Completed test kraken (00:01:01) 18:32:12 INFO - 2015-12-12 18:32:12,504 INFO : Starting test v8_7 18:32:12 INFO - 2015-12-12 18:32:12,504 DEBUG : operating with platform_type : linux_ 18:32:12 INFO - 2015-12-12 18:32:12,505 INFO : Initialising browser for v8_7 test... 18:32:12 INFO - 2015-12-12 18:32:12,511 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqgObmN/profile http://localhost:35838/getInfo.html 18:32:16 INFO - 2015-12-12 18:32:16,910 DEBUG : BROWSER_OUTPUT: __metrics Screen width/height:1600/1200 18:32:16 INFO - 2015-12-12 18:32:16,910 DEBUG : BROWSER_OUTPUT: colorDepth:24 18:32:16 INFO - 2015-12-12 18:32:16,910 DEBUG : BROWSER_OUTPUT: Browser inner width/height: 1024/697 18:32:16 INFO - 2015-12-12 18:32:16,910 DEBUG : BROWSER_OUTPUT: __metrics 18:32:17 INFO - 2015-12-12 18:32:17,027 DEBUG : BROWSER_OUTPUT: JavaScript error: resource://gre/modules/PerformanceStats.jsm, line 211: NS_ERROR_NOT_AVAILABLE: Component returned failure code: 0x80040111 (NS_ERROR_NOT_AVAILABLE) [nsIPerformanceStatsService.isMonitoringJank] 18:32:17 INFO - 2015-12-12 18:32:17,976 INFO : Browser initialized. 18:32:17 INFO - 2015-12-12 18:32:17,977 INFO : Running cycle 1/1 for v8_7 test... 18:32:17 INFO - 2015-12-12 18:32:17,977 DEBUG : command line: /builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpqgObmN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/v8_7/v8.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1 18:32:29 INFO - 2015-12-12 18:32:29,998 DEBUG : BROWSER_OUTPUT: RSS: Main: 143572992 18:32:29 INFO - 2015-12-12 18:32:29,998 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,456 DEBUG : BROWSER_OUTPUT: Cycle 1(1): loaded http://localhost:35838/tests/v8_7/run.html (next: http://localhost:35838/tests/v8_7/run.html) 18:32:51 INFO - 2015-12-12 18:32:51,714 DEBUG : BROWSER_OUTPUT: RSS: Main: 138272768 18:32:51 INFO - 2015-12-12 18:32:51,714 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,714 DEBUG : BROWSER_OUTPUT: __start_tp_report 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: _x_x_mozilla_page_load_details 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: |i|pagename|runs| 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: |0;Richards;167.0843776106934 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: |1;DeltaBlue;185.49434242255612 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: |2;Encrypt;279.56388034665923 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: |3;Decrypt;5202.072538860104 18:32:51 INFO - 2015-12-12 18:32:51,715 DEBUG : BROWSER_OUTPUT: |4;RayTrace;1074.1138560687432 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: |5;Earley;717.3601147776184 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: |6;Boyer;12207.317073170732 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: |7;RegExp;26025.641025641027 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: |8;Splay;595.605700712589 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: |9;NavierStokes;4840.579710144928 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: __end_tp_report 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: __startTimestamp1449973971708__endTimestamp 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: ------- Summary: start ------- 18:32:51 INFO - 2015-12-12 18:32:51,716 DEBUG : BROWSER_OUTPUT: Number of tests: 10 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: [#0] Richards Cycles:1 Average:167.08 Median:167.08 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: Values: 167.1 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: [#1] DeltaBlue Cycles:1 Average:185.49 Median:185.49 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: Values: 185.5 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: [#2] Encrypt Cycles:1 Average:279.56 Median:279.56 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: Values: 279.6 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: [#3] Decrypt Cycles:1 Average:5202.07 Median:5202.07 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: Values: 5202.1 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: [#4] RayTrace Cycles:1 Average:1074.11 Median:1074.11 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: Values: 1074.1 18:32:51 INFO - 2015-12-12 18:32:51,717 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: [#5] Earley Cycles:1 Average:717.36 Median:717.36 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: Values: 717.4 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: [#6] Boyer Cycles:1 Average:12207.32 Median:12207.32 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: Values: 12207.3 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,718 DEBUG : BROWSER_OUTPUT: [#7] RegExp Cycles:1 Average:26025.64 Median:26025.64 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: Values: 26025.6 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: [#8] Splay Cycles:1 Average:595.61 Median:595.61 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: Values: 595.6 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: [#9] NavierStokes Cycles:1 Average:4840.58 Median:4840.58 stddev:0.00 (0.0%) 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: Values: 4840.6 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: -------- Summary: end -------- 18:32:51 INFO - 2015-12-12 18:32:51,719 DEBUG : BROWSER_OUTPUT: 18:32:52 INFO - 2015-12-12 18:32:52,660 INFO : Browser exited with error code: 0 18:32:52 INFO - 2015-12-12 18:32:52,677 INFO : Completed test v8_7 (00:00:40) 18:32:52 INFO - 2015-12-12 18:32:52,733 INFO : Completed test suite (00:20:16) 18:32:52 INFO - 2015-12-12 18:32:52,733 DEBUG : Working with test: dromaeo_css 18:32:52 INFO - 2015-12-12 18:32:52,733 DEBUG : Generating results file: dromaeo_css 18:32:52 INFO - 2015-12-12 18:32:52,734 DEBUG : Working with test: kraken 18:32:52 INFO - 2015-12-12 18:32:52,734 DEBUG : Generating results file: kraken 18:32:52 INFO - 2015-12-12 18:32:52,734 INFO : javascript benchmark 18:32:52 INFO - 2015-12-12 18:32:52,735 DEBUG : Working with test: v8_7 18:32:52 INFO - 2015-12-12 18:32:52,735 DEBUG : Generating results file: v8_7 18:32:52 INFO - 2015-12-12 18:32:52,735 INFO : Posting result 0 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:32:52 INFO - 2015-12-12 18:32:52,782 DEBUG : process_Request line: dromaeo_css graph.html#tests=[[72,94,43]] 18:32:52 INFO - 2015-12-12 18:32:52,782 DEBUG : process_Request line: dromaeo_css 6242.61 graph.html#tests=[[72,94,43]] 18:32:52 INFO - 2015-12-12 18:32:52,782 INFO : Posting result 1 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:32:52 INFO - 2015-12-12 18:32:52,885 DEBUG : process_Request line: kraken 1556.70 graph.html#tests=[[232,94,43]] 18:32:52 INFO - 2015-12-12 18:32:52,885 INFO : Posting result 2 of 3 to http://graphs.mozilla.org/server/collect.cgi, attempt 0 18:32:52 INFO - 2015-12-12 18:32:52,929 DEBUG : process_Request line: v8_7 21341.91 graph.html#tests=[[230,94,43]] 18:32:52 INFO - 2015-12-12 18:32:52,931 INFO : javascript benchmark 18:32:52 INFO - 2015-12-12 18:32:52,934 INFO : PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [2652.456246469474, 2661.5874539446386, 2714.9864250678743, 2729.072115480736, 2732.0437846753653, 4628.1669299526075, 4745.430548334196, 4770.379850619419, 4802.303665968432, 4833.081714474251, 4639.8376056838015, 4735.1240020596215, 4742.359781429506, 4791.832285869995, 4800.207965685667, 4368.170047690936, 4450.132224216263, 4504.797284122223, 4508.954910450902, 4545.681802273825, 4400.031992961543, 4441.422615060038, 4477.865664030068, 4498.572635599629, 4547.636189104864, 4259.659227261812, 4330.588594083557, 4356.063446359017, 4431.069475410151, 4480.551944805526, 4449.132419178261, 4480.977595112036, 4483.193025255453, 4492.213862574069, 4517.254652982254, 4437.2013037653205, 4458.08609235106, 4460.8661740147845, 4467.195904737146, 4478.171538265398, 4494.865154045384, 4597.977010114962, 4612.215923293014, 4712.246040633482, 4721.952780472186, 5140.563052140573, 5178.508041736029, 5183.14478111115, 5198.92201616976, 5205.27126202332, 5144.922826157574, 5162.12243918531, 5164.612654050924, 5179.430262671103, 5196.818111366084, 4600.539946005406, 4705.6000239979985, 4716.434027916672, 4735.668503204812, 4802.639802014863, 5252.107141785906, 5294.047071527162, 5302.734863256822, 5306.28365170697, 5309.787608495694, 4802.73584952824, 5010.69935803853, 5013.649044566845, 5029.673071250357, 5064.215046667772, 5090.440051594322, 5168.664036837594, 5229.111051121318, 5267.420583735786, 5318.308619879391, 5143.974280128576, 5147.253648220987, 5149.845504634792, 5154.561862241677, 5194.4026436959475, 4818.542238487338, 4953.628477864175, 5101.928594995093, 5108.565771909355, 5117.155669314596, 4186.748795072305, 4232.830686772556, 4243.363495475642, 4296.183725092223, 4297.205017071852, 3716.4053751399647, 3758.437156195149, 3763.2285381496727, 3795.449659799369, 3796.1648437343742, 4214.30463973441, 4329.415528903558, 4351.173277077345, 4488.0, 4489.169503641837, 5155.252488389163, 5187.170052791535, 5187.559057480081, 5197.506236907488, 5201.2198170274005, 4470.731756094645, 4482.75344856036, 4486.192485352668, 4515.277555591089, 4516.435445569304, 4416.470023597189, 4444.8444304449185, 4551.726896386227, 4551.954480455153, 4578.175928332866, 4915.705057696549, 4929.580985616263, 4932.950670493321, 4947.307376967227, 4992.525710057539, 5174.6119041071315, 5186.481351864783, 5186.559142472932, 5191.6884986900905, 5195.142801437721, 4632.629389648888, 4751.477337492804, 4785.306130611111, 4820.903581928272, 4851.0, 5074.340335756476, 5095.694258344512, 5103.260027296095, 5111.080005598879, 5141.331626888481, 4869.1722407190155, 5157.561607263425, 5163.380394352552, 5165.354330708661, 5165.922511162411, 4882.023595280887, 4930.753462326941, 4934.851954441372, 4990.8752281193265, 5024.296598476143, 4993.325901003318, 5031.119554078095, 5050.747462626928, 5056.519630635084, 5088.0587091388215, 4627.421572303462, 4812.0, 4938.703677779345, 4953.108440480748, 4969.676970996874, 4950.158473059517, 5004.549590536723, 5045.8738531537, 5052.595792336678, 5087.847364579068, 4852.466228714909, 4945.480724524017, 4959.057779021974, 4961.50384961501, 5005.149124648746], "unit": "ms", "name": "dojo.html", "value": 4705.349040025599}, {"lowerIsBetter": false, "replicates": [538.3378444513248, 578.9015867302559, 585.2479563760565, 586.6743957103808, 598.9667823005313, 27063.729362706392, 35892.10269743254, 36315.455268171, 36416.45375319369, 36589.451158232645, 40729.18541629166, 41175.79412102943, 41250.38124428136, 41255.17489650205, 41423.58576414235, 7978.242067003625, 8112.269895709385, 8207.056188538334, 8230.794230144264, 8238.052623948233, 7822.139564647884, 7911.367090632764, 7975.162607926171, 7979.0, 7980.481268717544, 8021.318187954002, 8055.718049868256, 8089.838203235931, 8111.351091912633, 8113.148119447462, 7879.054513458393, 7925.28672419485, 7936.642851071716, 7950.880736788953, 7957.681692732283, 7918.841623167562, 8057.959710201411, 8062.838743225103, 8069.959650201711, 8081.7171399001345, 37253.627463725286, 37377.0, 37734.81132594347, 37761.24477510462, 37802.05494862623, 2856.8571571421344, 2869.5408734602365, 2986.3280761828632, 3043.771717121225, 3459.567554055743, 28547.857260713772, 29434.41131177386, 29486.26284342887, 29572.55641165384, 29592.408151836844, 3374.8312584370683, 3477.7565570410084, 3507.5440192774777, 3510.2102027043966, 3600.8019558924248, 169149.0, 170365.0, 170456.0, 170800.0, 171376.0, 51018.48981510138, 51406.22890656643, 51484.227736583984, 53245.733771330895, 54496.0, 69533.3046669537, 70612.64693676602, 70872.64563677149, 70979.0, 71023.2897671027, 12057.939710301567, 12293.692657683629, 12329.938350308192, 12331.630051098482, 12340.382980850922, 9933.751656208507, 10004.54979525923, 10052.195824334036, 10065.748356291006, 10086.092251697382, 19601.705974410394, 20143.697844532344, 20269.797302027087, 20455.181792728123, 20492.38522844317, 7565.886511702329, 7766.0, 7913.683452661829, 7940.483868548526, 7981.201879812089, 851.6082602003024, 901.5312037740339, 1051.2851261142343, 1051.4269723000982, 1052.9262951593314, 26448.8677556611, 26856.731432685814, 26890.193294201206, 26970.730292696822, 30280.394392112034, 921.8893732752113, 944.0795224656043, 959.4770849886828, 959.7840485890619, 1022.8465730140539, 12914.806277905838, 13215.14101583394, 13252.469901203844, 13305.534306299232, 16027.958182718086, 294448.0, 297824.0, 298692.0, 299226.0, 299933.0, 48911.51088489141, 49595.75202123966, 49617.0, 49688.25467617989, 49715.75142124339, 1214.5141943222782, 1214.6173955204083, 1221.645722740413, 1223.3516236394726, 1224.4612370556927, 27660.446791063663, 27759.572608833587, 27909.720902790712, 28401.28996775097, 28494.57258141088, 41335.0, 41891.162176755686, 41891.7905410471, 41948.3707744378, 42045.78977105095, 245674.0, 245679.0, 247073.0, 248008.0, 248266.0, 30187.547186792654, 39303.60696393, 40612.0, 40704.98237544085, 40762.0, 37127.44308835424, 38321.80839095786, 38638.0, 39103.80448097855, 39253.2149357017, 33619.663803361655, 34104.829475852464, 34239.0, 34434.31131377409, 34835.47746783851, 33731.662683373834, 34049.0, 34355.0, 34607.4808877862, 38884.2223155541, 33025.0, 33148.0, 33493.33013339671, 35442.0, 36408.63591364052, 28632.713672863003, 30347.0, 31008.844955775076, 31267.37465250637, 31286.217844554067, 23918.262077174746, 24010.0, 24098.03607855736, 24155.275341739776, 24313.270601881974], "unit": "ms", "name": "ext.html", "value": 17240.01127032058}, {"lowerIsBetter": false, "replicates": [333.8881474705972, 337.4785955698446, 338.9508521264418, 339.6213222257183, 340.1496259351621, 15131.697366052686, 15144.94202319073, 15892.125933073676, 16034.278457469412, 16565.0, 10121.291509594332, 10378.896211037887, 13927.164370137798, 15280.541583752478, 15302.693946121071, 4585.862424127282, 4611.0, 4705.952940470603, 4708.0819240248165, 4721.055788842228, 4635.768211589407, 4663.7668116594205, 4709.835155769549, 4710.670253082286, 4716.103940251342, 3499.562554680665, 3598.1904071583945, 4683.274092515666, 4691.953080469186, 5226.111561034614, 5302.814401495929, 5355.651882627636, 5370.650907690988, 5378.623496355237, 5393.622446428752, 4656.185167595657, 4810.39870016248, 4816.205326121186, 5141.460146684582, 5179.507946745053, 11100.888991110147, 11444.256123351955, 11589.478473468629, 18108.818911810977, 18111.0, 1806.2865168258595, 1818.6544556534348, 1850.2506484873647, 1897.9845782506432, 1967.9864869592172, 6990.7553235637015, 8845.469271843645, 8891.599878005505, 8919.10808919103, 9027.232685221765, 1751.7635119258864, 1771.7785276840395, 1803.774528183977, 1807.9728804067818, 1818.7999320074782, 52519.212211816855, 55317.723411383085, 55553.72223138899, 56131.71934140303, 56236.43763562394, 8886.955565222133, 9049.81900361989, 9244.075592440702, 9256.49089300095, 9265.397749146285, 11340.20618556693, 11357.20499565039, 11374.94312528432, 11378.089752819755, 11385.03227225695, 6517.706703198368, 6540.182477190351, 6582.407583317525, 6630.9336906630315, 6871.622060786704, 6796.490263230277, 6825.2833452486775, 6834.2482326944, 6843.281455447106, 6858.451323894176, 3859.9421008684894, 4088.0, 4108.568600296986, 4123.072308730571, 4175.039740859619, 1607.0759313394844, 1627.9430219942244, 1638.7787648667277, 1652.1986836384344, 1656.155360766018, 372.7409450431978, 374.5842115252068, 376.06735296464376, 886.7561420609384, 901.4681338010605, 9116.908830911738, 9123.863142052875, 9274.582643780912, 9304.8604270936, 9356.532173391302, 382.8927900187952, 383.26987089594365, 387.41113507469055, 398.4501388084425, 411.5657980830253, 4681.859544213679, 5054.444011158769, 5062.898742025212, 5107.668001579886, 5195.506426889439, 469797.0, 477016.0, 477550.0, 479740.0, 503430.0, 72520.0, 73802.63098684579, 74011.0, 74340.62829685817, 74653.0, 444.8509749234043, 448.27603420475697, 448.9830533840853, 452.20186371055115, 452.2492662180764, 9461.716148515556, 9547.188488978514, 9547.283953703361, 9667.661631842851, 9704.6118155273, 30597.694023059485, 31056.84471577718, 31706.841465792524, 32613.18467038248, 33190.33619327648, 36911.0, 39301.80349098236, 39994.60005400025, 41842.163156737304, 45275.77362113168, 8538.530380828866, 8766.211041006085, 9206.125418085272, 9215.387307221075, 9252.676156334495, 10505.422201778976, 10662.253642244968, 14176.362063706953, 15854.7621785671, 16223.432179873647, 13629.250391228577, 13733.313334333443, 13821.239831809347, 13837.238951857744, 13979.231142287272, 12277.570285039981, 12964.740705185655, 13019.869801301866, 13400.32998350098, 13702.10936289138, 13535.390907409386, 13836.654083647989, 14314.498992535211, 14655.413783448968, 14667.85332146665, 9014.729558113268, 9021.962474315274, 9061.864072038792, 9111.954440227757, 9115.0, 9695.66065187715, 9753.12221900004, 11377.488013039267, 13763.449462021808, 14345.426182952566], "unit": "ms", "name": "jquery.html", "value": 7229.876825232718}, {"lowerIsBetter": false, "replicates": [1495.1851241073628, 1597.9041257524545, 1677.320685122525, 1703.3101593854485, 1705.8805883588154, 5926.1110833374905, 6025.0, 6712.261651218362, 7063.0464887240205, 7065.187503437106, 6779.423748981342, 6940.965295173517, 7000.544964577299, 7002.054722612445, 7011.439084873211, 2022.5449273913398, 2031.908564114611, 2041.9387418377473, 2049.825764809993, 2050.2721533855492, 2065.4733043073993, 2069.9896500517475, 2089.4880754215237, 2110.124298416163, 2111.292716939827, 2011.7284166637462, 2028.0, 2039.8164165225128, 2067.6795096760025, 2071.2129390831537, 2094.539201375695, 2116.7883211678786, 2119.3853782403085, 2119.512512122205, 2120.289702949514, 2073.751149862026, 2112.039022244875, 2114.2600089968387, 2118.269197127004, 2122.0875023739786, 6959.826004349932, 7001.404880585156, 7012.158540975065, 7022.92977070233, 7025.578465292099, 4012.057166565855, 4059.985003749063, 4105.137921036586, 4117.608827161415, 4117.835286588563, 2568.2808813532242, 2589.9870500647376, 2627.2512333984914, 2698.0691661376795, 2703.0, 823.5717426938019, 828.0477450931417, 839.8026463781007, 844.0757370679158, 859.497194141428, 9472.57373418191, 9472.715818525387, 9475.194608458289, 9478.2417406608, 9497.857532137023, 3770.5852356240794, 3785.7917814520465, 3789.6210378962433, 3812.390017597171, 3853.9614603854166, 8291.170882911661, 8298.294644955125, 8298.875516867252, 8318.292945099734, 8348.582570871433, 4213.97893010533, 4225.492940847056, 4226.0, 4229.957700422957, 4249.617534421917, 3158.2262345724985, 3237.7409807215367, 3735.1782607826235, 3820.4842346283453, 3830.0999265172663, 3389.4576867701544, 3396.388650043016, 3446.3451944130534, 3455.0844026333043, 3463.3765922133753, 1066.461436974323, 1081.7998670245872, 1084.5499117866154, 1084.739662480999, 1095.2169199022737, 1011.544804837826, 1019.1031891935049, 1019.2152042926905, 1028.141501845952, 1035.9067683908333, 6756.560823546453, 6761.898571521431, 6766.357196066366, 6814.659267036628, 6830.282820303896, 409.2245195354786, 410.91781643671385, 418.5500586869128, 420.20790809324643, 420.4051267456576, 5079.644424890223, 5084.77118529667, 5088.618353623493, 5127.66670166438, 5141.511556402136, 8259.174082591813, 8398.0, 8486.236238738544, 8595.5702214889, 8691.521966291788, 8489.490630562186, 8491.915080849112, 8522.977242730913, 8616.698415555426, 8618.439801413013, 6549.181352330958, 6730.798076057726, 6772.492063095287, 6818.795436136924, 6825.146856642919, 6846.965765171142, 6847.554908930903, 6907.6891539879825, 6920.377166055079, 6920.826979325557, 7665.118511371264, 7719.343855772097, 7762.68949242024, 7769.7669069928, 7879.606019699107, 7728.7681369559, 7968.203179681986, 7980.241877021906, 8087.676492940452, 8110.756677299691, 5389.973050134724, 5674.375818659862, 6126.387361263838, 6308.968455157695, 6385.169927909342, 6430.935690643034, 6606.966965165336, 6644.501662375438, 6658.900116498159, 6668.233153187251, 6317.652529110759, 6362.268339141058, 6442.0, 6569.146011018537, 6612.801615951529, 5518.862028449321, 5624.100143976944, 5787.392323806108, 6248.218972628421, 6488.870222595428, 6256.7810126645345, 6300.873982520232, 6302.464290535172, 6302.621842689453, 6309.842253943688, 6017.097435384728, 6035.336113027475, 6064.909026364519, 6066.908996365147, 6084.087386892002, 5845.444682755132, 5929.584929054925, 5970.223870896755, 5983.970080149571, 5990.071538911476], "unit": "ms", "name": "mootools.html", "value": 3958.142006549836}, {"lowerIsBetter": false, "replicates": [294.543457640657, 296.5270393722013, 301.966783653798, 304.9130997665665, 308.74837007838596, 5641.5204707599905, 5673.290838645169, 5684.062129748591, 5705.429457054298, 5865.061590145578, 5648.491635752782, 5699.74351154198, 5737.082066869291, 5744.626599271044, 5749.712514374285, 4198.6641068714425, 4309.913801723964, 4319.956800431987, 4321.438213032301, 4323.4379530661045, 4299.634531064848, 4306.160298741746, 4323.2218200723855, 4334.97832510837, 4340.891477713051, 4290.935635965463, 4293.205756934977, 4297.162053399588, 4306.655467562588, 4310.836074259948, 4293.420388247578, 4318.395424640553, 4324.351347297899, 4327.199468098381, 4333.0, 3735.7384983051197, 3737.6449237322413, 3744.0, 3746.7002639788752, 3746.9625303746884, 5238.81664141757, 5282.234076058989, 5356.732163391854, 5439.211314359435, 5443.537299329561, 4915.778789954425, 4948.826791062332, 4950.67820591664, 4952.87617809554, 4955.554000139969, 5962.672053037081, 5999.400059994009, 5999.610025348338, 6001.7299221534695, 6005.579609427342, 3894.2600905827803, 4046.959530404717, 4047.2107938951917, 4051.311277082903, 4055.8580449684414, 6265.87468250637, 6318.71565779541, 6319.58922670025, 6324.7470101195895, 6332.620042797447, 4148.108156746314, 4163.91672166555, 4171.165766846643, 4176.644985176294, 4184.184084103632, 4217.451731274976, 4234.34367673005, 4236.4492615959725, 4238.19474299882, 4242.7242229255, 4742.099001189763, 4922.630802689741, 4923.876903077452, 4925.532074452921, 4926.458089610212, 5987.940120598825, 6014.729337179838, 6015.608985415934, 6017.247844019497, 6038.607490513103, 1467.757819959716, 1489.8361180270163, 1494.75336569467, 1497.4758834408087, 1499.0481044536798, 990.2424645146468, 991.8859331177007, 993.6522217224058, 993.9602415903428, 1005.331454582709, 5243.2659427680155, 5245.842624721265, 5258.789648414097, 5258.8948221036135, 5267.209918512254, 6127.479164271087, 6162.26052873658, 6193.225846769154, 6217.906731398942, 6224.844378890564, 5213.843584692465, 5226.529612334908, 5229.895402092013, 5235.267062611238, 5236.842894713165, 3299.5875515560556, 3348.5981682198294, 3385.2044769479644, 3388.0344101931323, 3401.9794061781367, 5740.1102829061565, 5872.970635146882, 5914.290285165807, 5940.435658612425, 5949.25634295713, 6448.290688024314, 6457.418832305115, 6463.418292353711, 6473.320301368382, 6476.870462590722, 5850.239468869105, 5878.853028674317, 5880.323762767251, 5887.087501437284, 5900.586958912835, 6183.412575805291, 6223.408776166257, 6261.906071408841, 6277.591956522811, 6295.96852015737, 6083.969580152071, 6103.11504831788, 6108.08378743192, 6118.47992920589, 6126.877462450815, 5872.588918775645, 5891.58758886891, 5896.616719913192, 5897.941020589911, 5898.8230353089475, 4004.4794176758, 4025.376066709665, 4037.798110094425, 4043.251998380309, 4044.8988775280854, 6084.0, 6206.727620837808, 6282.780102696383, 6285.214348206474, 6287.245530536365, 6007.429294217042, 6030.03519436888, 6030.969845150747, 6033.9698301508215, 6040.063790112364, 5992.790252341147, 6010.579259451796, 6020.277566692025, 6020.789272375621, 6027.758889644365, 5627.099664053731, 5843.094320380348, 5854.297484301911, 5892.351841297368, 5899.970500147472, 6078.300995385411, 6093.2078829751845, 6103.572749907464, 6113.938860611337, 6115.4190351916495, 5937.465628093492, 5940.46535811779, 5947.702614869326, 5950.910736339045, 5960.433758792908], "unit": "ms", "name": "prototype.html", "value": 4449.302834650222}, {"lowerIsBetter": false, "replicates": [669.4276393683401, 790.3242727468015, 790.6521130702492, 791.5211297165218, 804.21588950773, 11109.666709998693, 11271.041961433268, 11286.379249141293, 11296.491657875406, 11337.716557086076, 11823.467943942522, 11829.822552661695, 11859.229150105237, 11865.644030679072, 11879.227850189753, 795.1372760554791, 798.4530596541388, 803.5098589860181, 805.9032916050082, 809.6882700160422, 623.2925629410629, 626.4862812493757, 777.2810150610662, 796.0845028217567, 803.3854101612242, 776.7397921696239, 831.418007394823, 839.9832003359929, 843.7089204224533, 847.3560094328324, 827.1067247372823, 828.5691440450962, 832.0763952013231, 833.7582101190648, 835.482001159282, 616.6947361056306, 617.3918690090258, 622.8598565322811, 623.2085251730323, 624.9062640603901, 10166.440845753481, 10201.387916724945, 10278.486075696186, 10281.331713438603, 10313.793724125551, 10791.83812242817, 11357.659270221906, 11613.129015323884, 11648.417579121011, 12014.339211343373, 9368.53157342137, 9379.76550586234, 9463.574139163755, 9477.47873866937, 9532.90467095334, 3346.8159251241173, 3352.1619595101224, 3364.747643926715, 3393.100828280508, 3395.0, 39769.0, 39792.60207397918, 40099.19801603981, 40270.0, 40301.19397612061, 9209.861852072225, 9394.953025234967, 9433.0, 9466.479343636167, 9485.762855928519, 15874.841251587337, 15884.92057539705, 15957.760633590506, 15971.121588312755, 15993.0, 6140.969295153495, 6183.783567575113, 6260.561760676709, 6270.811875643738, 6346.777862774781, 3522.947155792665, 3524.2070534129616, 3569.37535931211, 3598.694111000542, 3660.853565857336, 2695.9191224263304, 2703.324168957761, 2735.288824905499, 2736.5758307462374, 2739.4384151248946, 464.76993888025646, 472.8179650834385, 483.43921051579997, 484.8085006422443, 491.08902984963095, 499.48303505871246, 506.25327641728154, 577.2985822226016, 579.325086274494, 596.7911231069178, 7622.0, 7914.287714105758, 8237.917620823715, 8315.29320007794, 8408.663653453794, 497.7959036794897, 509.7012473743444, 607.7963882099458, 608.0, 612.948411003268, 3388.0, 3641.6904563111902, 3676.4485327201132, 3683.613220611851, 3736.23407201523, 48008.0, 48107.27839082417, 48142.27786583204, 48810.755946220044, 51481.742591286806, 44825.32762008572, 45091.323630145576, 45279.0, 45770.31344529835, 46994.76502617465, 39797.0, 40769.18461630751, 41166.588334116874, 41268.58731412707, 42647.786761065996, 7745.148033716174, 7858.6070696466095, 7878.4091193161885, 8039.397045221749, 8336.541490217855, 30232.84883575568, 30256.0, 31021.379572408878, 31140.0, 31715.84142079275, 38791.22417551577, 39243.60756392399, 39351.0, 39485.01287467836, 39693.2061358777, 10736.62421815264, 10774.24580279373, 10791.298565593212, 11078.169137314573, 11140.220184587, 11267.26762760418, 11401.20191586581, 11455.885441145481, 11557.364344961108, 11593.826092608786, 10594.2584019121, 10660.573577057141, 10668.46657667098, 10720.249582529148, 10810.243282970117, 10854.34873907568, 11242.887571124184, 11472.483738231633, 11643.0, 11671.12466565028, 11034.337939723642, 11078.446077696244, 11093.50079246452, 11155.330680159217, 11462.94268528652, 9033.457992520469, 9103.772405689911, 9239.260859131387, 9392.95303523478, 9696.806063878541, 8552.273056789994, 8692.261157801404, 8967.730968070968, 9428.387154834914, 9471.194948429185], "unit": "ms", "name": "yui.html", "value": 5729.396742980319}], "name": "dromaeo_css", "value": 6242.606555863262}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [113.0, 108.0, 112.0, 113.0, 117.0, 125.0, 112.0, 113.0, 112.0, 115.0], "unit": "ms", "name": "ai-astar", "value": 114.0}, {"lowerIsBetter": true, "replicates": [158.0, 138.0, 157.0, 168.0, 133.0, 161.0, 155.0, 131.0, 176.0, 134.0], "unit": "ms", "name": "audio-beat-detection", "value": 151.1}, {"lowerIsBetter": true, "replicates": [164.0, 163.0, 162.0, 162.0, 161.0, 166.0, 169.0, 164.0, 166.0, 165.0], "unit": "ms", "name": "audio-dft", "value": 164.2}, {"lowerIsBetter": true, "replicates": [73.0, 73.0, 73.0, 74.0, 160.0, 74.0, 77.0, 75.0, 73.0, 159.0], "unit": "ms", "name": "audio-fft", "value": 91.1}, {"lowerIsBetter": true, "replicates": [95.0, 94.0, 128.0, 93.0, 94.0, 95.0, 129.0, 95.0, 96.0, 130.0], "unit": "ms", "name": "audio-oscillator", "value": 104.9}, {"lowerIsBetter": true, "replicates": [111.0, 110.0, 109.0, 110.0, 111.0, 110.0, 111.0, 110.0, 110.0, 111.0], "unit": "ms", "name": "imaging-gaussian-blur", "value": 110.3}, {"lowerIsBetter": true, "replicates": [148.0, 148.0, 147.0, 148.0, 147.0, 149.0, 148.0, 150.0, 148.0, 148.0], "unit": "ms", "name": "imaging-darkroom", "value": 148.1}, {"lowerIsBetter": true, "replicates": [96.0, 96.0, 97.0, 97.0, 98.0, 95.0, 96.0, 97.0, 95.0, 96.0], "unit": "ms", "name": "imaging-desaturate", "value": 96.3}, {"lowerIsBetter": true, "replicates": [75.0, 75.0, 73.0, 74.0, 76.0, 77.0, 76.0, 74.0, 74.0, 75.0], "unit": "ms", "name": "json-parse-financial", "value": 74.9}, {"lowerIsBetter": true, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 74.0, 68.0, 67.0, 68.0, 68.0], "unit": "ms", "name": "json-stringify-tinderbox", "value": 68.6}, {"lowerIsBetter": true, "replicates": [93.0, 86.0, 83.0, 84.0, 84.0, 86.0, 85.0, 88.0, 96.0, 100.0], "unit": "ms", "name": "stanford-crypto-aes", "value": 88.5}, {"lowerIsBetter": true, "replicates": [130.0, 119.0, 125.0, 126.0, 125.0, 135.0, 133.0, 126.0, 137.0, 125.0], "unit": "ms", "name": "stanford-crypto-ccm", "value": 128.1}, {"lowerIsBetter": true, "replicates": [140.0, 150.0, 144.0, 148.0, 150.0, 139.0, 140.0, 153.0, 150.0, 139.0], "unit": "ms", "name": "stanford-crypto-pbkdf2", "value": 145.3}, {"lowerIsBetter": true, "replicates": [71.0, 72.0, 71.0, 71.0, 72.0, 72.0, 69.0, 70.0, 73.0, 72.0], "unit": "ms", "name": "stanford-crypto-sha256-iterative", "value": 71.3}], "name": "kraken", "value": 1556.6999999999996}, {"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [167.0843776106934], "unit": "score", "name": "Richards", "value": 211.28247000000007}, {"lowerIsBetter": false, "replicates": [185.49434242255612], "unit": "score", "name": "DeltaBlue", "value": 356.44213800000017}, {"lowerIsBetter": false, "replicates": [279.56388034665923], "unit": "score", "name": "Encrypt", "value": 952.1294369999999}, {"lowerIsBetter": false, "replicates": [5202.072538860104], "unit": "score", "name": "Decrypt", "value": 51.16825996015938}, {"lowerIsBetter": false, "replicates": [1074.1138560687432], "unit": "score", "name": "RayTrace", "value": 688.9297590000002}, {"lowerIsBetter": false, "replicates": [717.3601147776184], "unit": "score", "name": "Earley", "value": 929.0494220000003}, {"lowerIsBetter": false, "replicates": [12207.317073170732], "unit": "score", "name": "Boyer", "value": 54.595370629370635}, {"lowerIsBetter": false, "replicates": [26025.641025641027], "unit": "score", "name": "RegExp", "value": 35.003364532019674}, {"lowerIsBetter": false, "replicates": [595.605700712589], "unit": "score", "name": "Splay", "value": 136.82038285144571}, {"lowerIsBetter": false, "replicates": [4840.579710144928], "unit": "score", "name": "NavierStokes", "value": 306.5748502994012}], "name": "v8_7", "value": 21341.911597650706}]} 18:32:52 INFO - RETURN: dromaeo_css: 6242.61 18:32:52 INFO - RETURN: kraken: 1556.70 18:32:52 INFO - RETURN: v8_7: 21341.91 18:32:52 INFO - TinderboxPrint: TalosResult: {"graphserver": {"v8_7": {"url": "http://graphs.mozilla.org/graph.html#tests=[[230,94,43]]", "result": "21341.91"}, "kraken": {"url": "http://graphs.mozilla.org/graph.html#tests=[[232,94,43]]", "result": "1556.70"}, "dromaeo_css": {"url": "http://graphs.mozilla.org/graph.html#tests=[[72,94,43]]", "result": "6242.61"}}} 18:32:52 INFO - Return code: 0 18:32:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 18:32:53 INFO - # TBPL SUCCESS # 18:32:53 INFO - Running post-action listener: _resource_record_post_action 18:32:53 INFO - Running post-run listener: _resource_record_post_run 18:32:54 INFO - Total resource usage - Wall time: 1232s; CPU: 13.0%; Read bytes: 0; Write bytes: 507510784; Read time: 0; Write time: 302080 18:32:54 INFO - install - Wall time: 15s; CPU: 13.0%; Read bytes: 0; Write bytes: 231120896; Read time: 0; Write time: 173320 18:32:54 INFO - run-tests - Wall time: 1218s; CPU: 13.0%; Read bytes: 0; Write bytes: 275980288; Read time: 0; Write time: 128740 18:32:54 INFO - Running post-run listener: _upload_blobber_files 18:32:54 INFO - Blob upload gear active. 18:32:54 INFO - There are no files to upload in the directory. Skipping the blob upload mechanism ... 18:32:54 INFO - Copying logs to upload dir... 18:32:54 INFO - mkdir: /builds/slave/test/build/upload/logs program finished with exit code 0 elapsedTime=1278.312741 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2015-12-12 18:32:52.469789) ========= ========= Started set props: build_url (results: 0, elapsed: 0 secs) (at 2015-12-12 18:32:52.474008) ========= 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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False build_url:https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/artifacts/public/build/firefox-45.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.010006 build_url: 'https://queue.taskcluster.net/v1/task/2M5W4wm7TF-bTTziG0digQ/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-12-12 18:32:52.526693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:32:52.527248) ========= 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=49ca1b5d70b54a29b055e1ff000001bd-1449971728.578062-716285567 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004503 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2015-12-12 18:32:52.578294) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-12 18:32:52.578623) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2015-12-12 18:32:52.578963) ========= ========= Total master_lag: 0.24 =========