builder: fx-team_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-025 starttime: 1465019898.07 results: success (0) buildid: 20160603203027 builduid: ef5926aa5cc042ab8d1a21c7ff36de3b revision: 67c37ed1a25f92cac6294cb70af97e95515c30e2 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.069232) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.069790) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.070189) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.106346) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.106695) ========= 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=232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-03 22:58:18-- 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% 228M=0s 2016-06-03 22:58:18 (228 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123889 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.244514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.244835) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', '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=232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.018056 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.295931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:18.296296) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 67c37ed1a25f92cac6294cb70af97e95515c30e2 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 67c37ed1a25f92cac6294cb70af97e95515c30e2 --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=232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-03 22:58:18,790 truncating revision to first 12 chars 2016-06-03 22:58:18,790 Setting DEBUG logging. 2016-06-03 22:58:18,790 attempt 1/10 2016-06-03 22:58:18,790 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/67c37ed1a25f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-03 22:58:19,367 unpacking tar archive at: fx-team-67c37ed1a25f/testing/mozharness/ program finished with exit code 0 elapsedTime=0.891872 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:19.201636) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:19.201954) ========= script_repo_revision: 67c37ed1a25f92cac6294cb70af97e95515c30e2 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:19.202387) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:19.202724) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-03 22:58:19.211986) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-03 22:58:19.212301) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] 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=232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:58:19 INFO - MultiFileLogger online at 20160603 22:58:19 in /builds/slave/test 22:58:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 22:58:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:58:19 INFO - {'append_to_log': False, 22:58:19 INFO - 'base_work_dir': '/builds/slave/test', 22:58:19 INFO - 'blob_upload_branch': 'Fx-Team', 22:58:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:58:19 INFO - 'branch': 'Fx-Team', 22:58:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:58:19 INFO - 'config_files': ('talos/linux_config.py',), 22:58:19 INFO - 'default_actions': ('clobber', 22:58:19 INFO - 'read-buildbot-config', 22:58:19 INFO - 'download-and-extract', 22:58:19 INFO - 'populate-webroot', 22:58:19 INFO - 'create-virtualenv', 22:58:19 INFO - 'install', 22:58:19 INFO - 'run-tests'), 22:58:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:58:19 INFO - 'download_minidump_stackwalk': True, 22:58:19 INFO - 'download_symbols': 'ondemand', 22:58:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:58:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:58:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:58:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:58:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:58:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:58:19 INFO - 'installer_path': 'installer.exe', 22:58:19 INFO - 'log_level': 'info', 22:58:19 INFO - 'log_name': 'talos', 22:58:19 INFO - 'log_to_console': True, 22:58:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:58:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:58:19 INFO - 'opt_config_files': (), 22:58:19 INFO - 'pip_index': False, 22:58:19 INFO - 'sps_profile': False, 22:58:19 INFO - 'sps_profile_interval': 0, 22:58:19 INFO - 'suite': 'dromaeojs', 22:58:19 INFO - 'system_bits': '32', 22:58:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:58:19 INFO - 'title': 'talos-linux64-ix-025', 22:58:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:58:19 INFO - 'use_talos_json': True, 22:58:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:58:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:58:19 INFO - 'work_dir': 'build'} 22:58:19 INFO - ##### 22:58:19 INFO - ##### Running clobber step. 22:58:19 INFO - ##### 22:58:19 INFO - Running pre-action listener: _resource_record_pre_action 22:58:19 INFO - Running main action method: clobber 22:58:19 INFO - rmtree: /builds/slave/test/build 22:58:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:58:20 INFO - Running post-action listener: _resource_record_post_action 22:58:20 INFO - ##### 22:58:20 INFO - ##### Finished clobber step (success) 22:58:20 INFO - ##### 22:58:20 INFO - ##### 22:58:20 INFO - ##### Running read-buildbot-config step. 22:58:20 INFO - ##### 22:58:20 INFO - Running pre-action listener: _resource_record_pre_action 22:58:20 INFO - Running main action method: read_buildbot_config 22:58:20 INFO - Using buildbot properties: 22:58:20 INFO - { 22:58:20 INFO - "project": "", 22:58:20 INFO - "product": "firefox", 22:58:20 INFO - "script_repo_revision": "production", 22:58:20 INFO - "builddir": "fx-team_ubuntu64_hw_test-dromaeojs", 22:58:20 INFO - "repository": "", 22:58:20 INFO - "buildername": "Ubuntu HW 12.04 x64 fx-team pgo talos dromaeojs", 22:58:20 INFO - "buildid": "20160603203027", 22:58:20 INFO - "pgo_build": "True", 22:58:20 INFO - "basedir": "/builds/slave/test", 22:58:20 INFO - "buildnumber": 152, 22:58:20 INFO - "slavename": "talos-linux64-ix-025", 22:58:20 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 22:58:20 INFO - "platform": "ubuntu64_hw", 22:58:20 INFO - "scheduler": "tests-fx-team-linux64-pgo-talos", 22:58:20 INFO - "branch": "fx-team", 22:58:20 INFO - "repo_path": "integration/fx-team", 22:58:20 INFO - "revision": "67c37ed1a25f92cac6294cb70af97e95515c30e2", 22:58:20 INFO - "stage_platform": "linux64-pgo", 22:58:20 INFO - "builduid": "ef5926aa5cc042ab8d1a21c7ff36de3b", 22:58:20 INFO - "slavebuilddir": "test" 22:58:20 INFO - } 22:58:20 INFO - Found installer url https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:58:20 INFO - Running post-action listener: _resource_record_post_action 22:58:20 INFO - ##### 22:58:20 INFO - ##### Finished read-buildbot-config step (success) 22:58:20 INFO - ##### 22:58:20 INFO - ##### 22:58:20 INFO - ##### Running download-and-extract step. 22:58:20 INFO - ##### 22:58:20 INFO - Running pre-action listener: _resource_record_pre_action 22:58:20 INFO - Running main action method: download_and_extract 22:58:20 INFO - mkdir: /builds/slave/test/build/tests 22:58:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:58:20 INFO - https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:58:20 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:58:20 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:58:20 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:58:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:58:22 INFO - Downloaded 1361 bytes. 22:58:22 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:58:22 INFO - Using the following test package requirements: 22:58:22 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:58:22 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:58:22 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:58:22 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'jsshell-linux-x86_64.zip'], 22:58:22 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:58:22 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:58:22 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:58:22 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:58:22 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:58:22 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:58:22 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:58:22 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:58:22 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')]} 22:58:22 INFO - https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:58:22 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:58:22 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:58:22 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:58:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:58:24 INFO - Downloaded 24440958 bytes. 22:58:24 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:58:24 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:58:25 INFO - Return code: 0 22:58:25 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:58:25 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')]} 22:58:25 INFO - https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:58:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:58:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:58:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:58:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:58:29 INFO - Downloaded 24440958 bytes. 22:58:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:58:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:58:31 INFO - Return code: 0 22:58:31 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')]} 22:58:31 INFO - https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:58:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:58:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:58:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:58:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:58:32 INFO - Downloaded 10833369 bytes. 22:58:32 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:58:32 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:58:32 INFO - Return code: 0 22:58:32 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')]} 22:58:32 INFO - https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:58:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:58:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:58:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:58:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:58:34 INFO - Downloaded 59170076 bytes. 22:58:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:58:34 INFO - mkdir: /builds/slave/test/properties 22:58:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:58:34 INFO - Writing to file /builds/slave/test/properties/build_url 22:58:34 INFO - Contents: 22:58:34 INFO - build_url:https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:58:34 INFO - Running post-action listener: _resource_record_post_action 22:58:34 INFO - Running post-action listener: set_extra_try_arguments 22:58:34 INFO - ##### 22:58:34 INFO - ##### Finished download-and-extract step (success) 22:58:34 INFO - ##### 22:58:34 INFO - ##### 22:58:34 INFO - ##### Running populate-webroot step. 22:58:34 INFO - ##### 22:58:34 INFO - Running pre-action listener: _resource_record_pre_action 22:58:34 INFO - Running main action method: populate_webroot 22:58:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:58:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:58:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:58:34 INFO - '--tppagecycles', 22:58:34 INFO - '7'], 22:58:34 INFO - 'tests': ['tsvgm']}}, 22:58:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:58:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:58:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:58:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:58:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:58:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['damp', 'tps']}, 22:58:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'tests': ['damp', 'tps']}, 22:58:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['dromaeo_dom']}, 22:58:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:58:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other-e10s': {'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['a11yr', 22:58:34 INFO - 'ts_paint', 22:58:34 INFO - 'tpaint', 22:58:34 INFO - 'sessionrestore', 22:58:34 INFO - 'sessionrestore_no_auto_restore', 22:58:34 INFO - 'tabpaint']}, 22:58:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tsvgx', 22:58:34 INFO - 'tsvgr_opacity', 22:58:34 INFO - 'tart', 22:58:34 INFO - 'tscrollx', 22:58:34 INFO - 'cart']}, 22:58:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:58:34 INFO - 'tsvgr_opacity', 22:58:34 INFO - 'tart', 22:58:34 INFO - 'tscrollx', 22:58:34 INFO - 'cart']}, 22:58:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s'], 22:58:34 INFO - 'tests': ['tp5o']}, 22:58:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'tests': ['tp5o']}, 22:58:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'talos_options': ['--disable-e10s', 22:58:34 INFO - '--xperf_path', 22:58:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:34 INFO - 'tests': ['tp5n']}, 22:58:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:58:34 INFO - 'talos_options': ['--xperf_path', 22:58:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:58:34 INFO - 'tests': ['tp5n']}}, 22:58:34 INFO - 'talos.zip': {'path': '', 22:58:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:58:34 INFO - Running post-action listener: _resource_record_post_action 22:58:34 INFO - ##### 22:58:34 INFO - ##### Finished populate-webroot step (success) 22:58:34 INFO - ##### 22:58:34 INFO - ##### 22:58:34 INFO - ##### Running create-virtualenv step. 22:58:34 INFO - ##### 22:58:34 INFO - Running pre-action listener: _resource_record_pre_action 22:58:34 INFO - Running main action method: create_virtualenv 22:58:34 INFO - Creating virtualenv /builds/slave/test/build/venv 22:58:34 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 22:58:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:58:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:58:34 INFO - Using real prefix '/usr' 22:58:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:58:35 INFO - Installing distribute.............................................................................................................................................................................................done. 22:58:37 INFO - Installing pip.................done. 22:58:37 INFO - Return code: 0 22:58:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:58:37 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')]} 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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')]} 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 22:58:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 22:58:37 INFO - Using env: {'DISPLAY': ':0', 22:58:37 INFO - 'HOME': '/home/cltbld', 22:58:37 INFO - 'LANG': 'en_US.UTF-8', 22:58:37 INFO - 'LANGUAGE': 'en_US:en', 22:58:37 INFO - 'LOGNAME': 'cltbld', 22:58:37 INFO - 'MAIL': '/var/mail/cltbld', 22:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:37 INFO - 'MOZ_NO_REMOTE': '1', 22:58:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:37 INFO - 'NO_EM_RESTART': '1', 22:58:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:37 INFO - 'PWD': '/builds/slave/test', 22:58:37 INFO - 'SHELL': '/bin/bash', 22:58:37 INFO - 'SHLVL': '1', 22:58:37 INFO - 'TERM': 'linux', 22:58:37 INFO - 'TMOUT': '86400', 22:58:37 INFO - 'USER': 'cltbld', 22:58:37 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:37 INFO - '_': '/tools/buildbot/bin/python'} 22:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:37 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 22:58:37 INFO - Cleaning up... 22:58:37 INFO - Return code: 0 22:58:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:58:37 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')]} 22:58:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 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')]} 22:58:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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>=3.1.1'] in /builds/slave/test/build 22:58:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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>=3.1.1 22:58:37 INFO - Using env: {'DISPLAY': ':0', 22:58:37 INFO - 'HOME': '/home/cltbld', 22:58:37 INFO - 'LANG': 'en_US.UTF-8', 22:58:37 INFO - 'LANGUAGE': 'en_US:en', 22:58:37 INFO - 'LOGNAME': 'cltbld', 22:58:37 INFO - 'MAIL': '/var/mail/cltbld', 22:58:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:37 INFO - 'MOZ_NO_REMOTE': '1', 22:58:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:37 INFO - 'NO_EM_RESTART': '1', 22:58:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:37 INFO - 'PWD': '/builds/slave/test', 22:58:37 INFO - 'SHELL': '/bin/bash', 22:58:37 INFO - 'SHLVL': '1', 22:58:37 INFO - 'TERM': 'linux', 22:58:37 INFO - 'TMOUT': '86400', 22:58:37 INFO - 'USER': 'cltbld', 22:58:37 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:37 INFO - '_': '/tools/buildbot/bin/python'} 22:58:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:37 INFO - Downloading/unpacking psutil>=3.1.1 22:58:37 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 22:58:37 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 22:58:37 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 22:58:37 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 22:58:37 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 22:58:37 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 22:58:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:58:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:39 INFO - Installing collected packages: psutil 22:58:39 INFO - Running setup.py install for psutil 22:58:39 INFO - building 'psutil._psutil_linux' extension 22:58:39 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 22:58:39 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 22:58:40 INFO - building 'psutil._psutil_posix' extension 22:58:40 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 22:58:40 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 22:58:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:58:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:58:40 INFO - Successfully installed psutil 22:58:40 INFO - Cleaning up... 22:58:40 INFO - Return code: 0 22:58:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:58:40 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')]} 22:58:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:40 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')]} 22:58:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:40 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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.3'] in /builds/slave/test/build 22:58:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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.3 22:58:40 INFO - Using env: {'DISPLAY': ':0', 22:58:40 INFO - 'HOME': '/home/cltbld', 22:58:40 INFO - 'LANG': 'en_US.UTF-8', 22:58:40 INFO - 'LANGUAGE': 'en_US:en', 22:58:40 INFO - 'LOGNAME': 'cltbld', 22:58:40 INFO - 'MAIL': '/var/mail/cltbld', 22:58:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:40 INFO - 'MOZ_NO_REMOTE': '1', 22:58:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:40 INFO - 'NO_EM_RESTART': '1', 22:58:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:40 INFO - 'PWD': '/builds/slave/test', 22:58:40 INFO - 'SHELL': '/bin/bash', 22:58:40 INFO - 'SHLVL': '1', 22:58:40 INFO - 'TERM': 'linux', 22:58:40 INFO - 'TMOUT': '86400', 22:58:40 INFO - 'USER': 'cltbld', 22:58:40 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:40 INFO - '_': '/tools/buildbot/bin/python'} 22:58:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:58:40 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 22:58:40 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 22:58:40 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 22:58:40 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 22:58:40 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 22:58:40 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 22:58:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:58:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:58:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:58:42 INFO - Installing collected packages: mozsystemmonitor 22:58:42 INFO - Running setup.py install for mozsystemmonitor 22:58:42 INFO - Successfully installed mozsystemmonitor 22:58:42 INFO - Cleaning up... 22:58:42 INFO - Return code: 0 22:58:42 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:58:42 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')]} 22:58:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:42 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')]} 22:58:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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==2.5.1'] in /builds/slave/test/build 22:58:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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==2.5.1 22:58:42 INFO - Using env: {'DISPLAY': ':0', 22:58:42 INFO - 'HOME': '/home/cltbld', 22:58:42 INFO - 'LANG': 'en_US.UTF-8', 22:58:42 INFO - 'LANGUAGE': 'en_US:en', 22:58:42 INFO - 'LOGNAME': 'cltbld', 22:58:42 INFO - 'MAIL': '/var/mail/cltbld', 22:58:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:42 INFO - 'MOZ_NO_REMOTE': '1', 22:58:42 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:42 INFO - 'NO_EM_RESTART': '1', 22:58:42 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:42 INFO - 'PWD': '/builds/slave/test', 22:58:42 INFO - 'SHELL': '/bin/bash', 22:58:42 INFO - 'SHLVL': '1', 22:58:42 INFO - 'TERM': 'linux', 22:58:42 INFO - 'TMOUT': '86400', 22:58:42 INFO - 'USER': 'cltbld', 22:58:42 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:42 INFO - '_': '/tools/buildbot/bin/python'} 22:58:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:42 INFO - Downloading/unpacking jsonschema==2.5.1 22:58:42 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 22:58:42 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 22:58:42 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 22:58:42 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 22:58:42 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 22:58:42 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 22:58:44 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:58:44 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 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 22:58:44 INFO - Downloading functools32-3.2.3-2.tar.gz 22:58:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:58:44 INFO - warning: no files found matching '*.txt' 22:58:44 INFO - no previously-included directories found matching 'build' 22:58:44 INFO - no previously-included directories found matching 'dist' 22:58:44 INFO - no previously-included directories found matching '.git*' 22:58:44 INFO - Installing collected packages: jsonschema, functools32 22:58:45 INFO - Running setup.py install for functools32 22:58:45 INFO - warning: no files found matching '*.txt' 22:58:45 INFO - no previously-included directories found matching 'build' 22:58:45 INFO - no previously-included directories found matching 'dist' 22:58:45 INFO - no previously-included directories found matching '.git*' 22:58:45 INFO - Successfully installed jsonschema functools32 22:58:45 INFO - Cleaning up... 22:58:45 INFO - Return code: 0 22:58:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:58:45 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')]} 22:58:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:45 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')]} 22:58:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:45 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 22:58:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 22:58:45 INFO - Using env: {'DISPLAY': ':0', 22:58:45 INFO - 'HOME': '/home/cltbld', 22:58:45 INFO - 'LANG': 'en_US.UTF-8', 22:58:45 INFO - 'LANGUAGE': 'en_US:en', 22:58:45 INFO - 'LOGNAME': 'cltbld', 22:58:45 INFO - 'MAIL': '/var/mail/cltbld', 22:58:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:45 INFO - 'MOZ_NO_REMOTE': '1', 22:58:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:45 INFO - 'NO_EM_RESTART': '1', 22:58:45 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:45 INFO - 'PWD': '/builds/slave/test', 22:58:45 INFO - 'SHELL': '/bin/bash', 22:58:45 INFO - 'SHLVL': '1', 22:58:45 INFO - 'TERM': 'linux', 22:58:45 INFO - 'TMOUT': '86400', 22:58:45 INFO - 'USER': 'cltbld', 22:58:45 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:45 INFO - '_': '/tools/buildbot/bin/python'} 22:58:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:45 INFO - Downloading/unpacking blobuploader==1.2.4 22:58:45 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 22:58:45 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 22:58:45 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 22:58:45 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 22:58:45 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 22:58:45 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 22:58:47 INFO - Downloading blobuploader-1.2.4.tar.gz 22:58:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:58:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:58:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 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 22:58:47 INFO - Downloading docopt-0.6.1.tar.gz 22:58:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:58:48 INFO - Installing collected packages: blobuploader, requests, docopt 22:58:48 INFO - Running setup.py install for blobuploader 22:58:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:58:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:58:48 INFO - Running setup.py install for requests 22:58:48 INFO - Running setup.py install for docopt 22:58:48 INFO - Successfully installed blobuploader requests docopt 22:58:48 INFO - Cleaning up... 22:58:48 INFO - Return code: 0 22:58:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:48 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')]} 22:58:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:48 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')]} 22:58:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--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 22:58:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --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 22:58:48 INFO - Using env: {'DISPLAY': ':0', 22:58:48 INFO - 'HOME': '/home/cltbld', 22:58:48 INFO - 'LANG': 'en_US.UTF-8', 22:58:48 INFO - 'LANGUAGE': 'en_US:en', 22:58:48 INFO - 'LOGNAME': 'cltbld', 22:58:48 INFO - 'MAIL': '/var/mail/cltbld', 22:58:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:48 INFO - 'MOZ_NO_REMOTE': '1', 22:58:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:48 INFO - 'NO_EM_RESTART': '1', 22:58:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:48 INFO - 'PWD': '/builds/slave/test', 22:58:48 INFO - 'SHELL': '/bin/bash', 22:58:48 INFO - 'SHLVL': '1', 22:58:48 INFO - 'TERM': 'linux', 22:58:48 INFO - 'TMOUT': '86400', 22:58:48 INFO - 'USER': 'cltbld', 22:58:48 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:48 INFO - '_': '/tools/buildbot/bin/python'} 22:58:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:48 INFO - Running setup.py (path:/tmp/pip-0ozJec-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:48 INFO - Running setup.py (path:/tmp/pip-TSnyHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:48 INFO - Running setup.py (path:/tmp/pip-hj8RvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:49 INFO - Running setup.py (path:/tmp/pip-FlXHBj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:49 INFO - Running setup.py (path:/tmp/pip-tVnpf5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:49 INFO - Running setup.py (path:/tmp/pip-TBDxR7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:49 INFO - Running setup.py (path:/tmp/pip-VMYioa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:49 INFO - Running setup.py (path:/tmp/pip-n767QR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:49 INFO - Running setup.py (path:/tmp/pip-cdb1o4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:49 INFO - Running setup.py (path:/tmp/pip-a1mw5j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:49 INFO - Running setup.py (path:/tmp/pip-8oBU44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:49 INFO - Running setup.py (path:/tmp/pip-c07T6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:49 INFO - Running setup.py (path:/tmp/pip-DQz7RW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:49 INFO - Running setup.py (path:/tmp/pip-zA7j0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:49 INFO - Running setup.py (path:/tmp/pip-Dqni49-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:49 INFO - Running setup.py (path:/tmp/pip-Fzr6lV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:49 INFO - Running setup.py (path:/tmp/pip-rFcpR3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:58:50 INFO - Running setup.py install for manifestparser 22:58:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Running setup.py install for mozcrash 22:58:50 INFO - Running setup.py install for mozdebug 22:58:50 INFO - Running setup.py install for mozdevice 22:58:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Running setup.py install for mozfile 22:58:50 INFO - Running setup.py install for mozhttpd 22:58:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Running setup.py install for mozinfo 22:58:50 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Running setup.py install for mozInstall 22:58:50 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:58:50 INFO - Running setup.py install for mozleak 22:58:50 INFO - Running setup.py install for mozlog 22:58:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for moznetwork 22:58:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozprocess 22:58:51 INFO - Running setup.py install for mozprofile 22:58:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozrunner 22:58:51 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Running setup.py install for mozscreenshot 22:58:51 INFO - Running setup.py install for moztest 22:58:51 INFO - Running setup.py install for mozversion 22:58:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:58:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:58:51 INFO - Cleaning up... 22:58:51 INFO - Return code: 0 22:58:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58:51 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')]} 22:58:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:51 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')]} 22:58:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 22:58:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 22:58:51 INFO - Using env: {'DISPLAY': ':0', 22:58:51 INFO - 'HOME': '/home/cltbld', 22:58:51 INFO - 'LANG': 'en_US.UTF-8', 22:58:51 INFO - 'LANGUAGE': 'en_US:en', 22:58:51 INFO - 'LOGNAME': 'cltbld', 22:58:51 INFO - 'MAIL': '/var/mail/cltbld', 22:58:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:51 INFO - 'MOZ_NO_REMOTE': '1', 22:58:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:51 INFO - 'NO_EM_RESTART': '1', 22:58:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:51 INFO - 'PWD': '/builds/slave/test', 22:58:51 INFO - 'SHELL': '/bin/bash', 22:58:51 INFO - 'SHLVL': '1', 22:58:51 INFO - 'TERM': 'linux', 22:58:51 INFO - 'TMOUT': '86400', 22:58:51 INFO - 'USER': 'cltbld', 22:58:51 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:51 INFO - '_': '/tools/buildbot/bin/python'} 22:58:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:58:52 INFO - Running setup.py (path:/tmp/pip-FbWxWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:58:52 INFO - Running setup.py (path:/tmp/pip-f48BZU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:58:52 INFO - Running setup.py (path:/tmp/pip-OZwwcE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:58:52 INFO - Running setup.py (path:/tmp/pip-iQpsVV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:58:52 INFO - Running setup.py (path:/tmp/pip-AkgRop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:58:52 INFO - Running setup.py (path:/tmp/pip-vVptrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:58:52 INFO - Running setup.py (path:/tmp/pip-GyRReA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:58:52 INFO - Running setup.py (path:/tmp/pip-SuJ7Ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:58:52 INFO - Running setup.py (path:/tmp/pip-6ArNlN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:58:52 INFO - Running setup.py (path:/tmp/pip-665Acr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:58:52 INFO - Running setup.py (path:/tmp/pip-Z37vqj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:58:52 INFO - Running setup.py (path:/tmp/pip-Pe7ZTh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:58:52 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:58:52 INFO - Running setup.py (path:/tmp/pip-AKvCWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:58:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 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)) 22:58:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:58:52 INFO - Running setup.py (path:/tmp/pip-uzTbOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:53 INFO - Running setup.py (path:/tmp/pip-CrOcUn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:58:53 INFO - Running setup.py (path:/tmp/pip-NSCHk_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:58:53 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)) 22:58:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:58:53 INFO - Running setup.py (path:/tmp/pip-4UpYGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:58:53 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)) 22:58:53 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:58:53 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.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:58:53 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)) 22:58:53 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:58:53 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.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:58:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:58:53 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 22:58:53 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 22:58:53 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 22:58:53 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 22:58:53 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 22:58:53 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 22:58:55 INFO - Downloading blessings-1.6.tar.gz 22:58:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:58:55 INFO - Installing collected packages: blessings 22:58:55 INFO - Running setup.py install for blessings 22:58:55 INFO - Successfully installed blessings 22:58:55 INFO - Cleaning up... 22:58:55 INFO - Return code: 0 22:58:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:58:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:55 INFO - Reading from file tmpfile_stdout 22:58:55 INFO - Current package versions: 22:58:55 INFO - argparse == 1.2.1 22:58:55 INFO - blessings == 1.6 22:58:55 INFO - blobuploader == 1.2.4 22:58:55 INFO - docopt == 0.6.1 22:58:55 INFO - functools32 == 3.2.3-2 22:58:55 INFO - jsonschema == 2.5.1 22:58:55 INFO - manifestparser == 1.1 22:58:55 INFO - mozInstall == 1.12 22:58:55 INFO - mozcrash == 0.17 22:58:55 INFO - mozdebug == 0.1 22:58:55 INFO - mozdevice == 0.48 22:58:55 INFO - mozfile == 1.2 22:58:55 INFO - mozhttpd == 0.7 22:58:55 INFO - mozinfo == 0.9 22:58:55 INFO - mozleak == 0.1 22:58:55 INFO - mozlog == 3.2 22:58:55 INFO - moznetwork == 0.27 22:58:55 INFO - mozprocess == 0.22 22:58:55 INFO - mozprofile == 0.28 22:58:55 INFO - mozrunner == 6.11 22:58:55 INFO - mozscreenshot == 0.1 22:58:55 INFO - mozsystemmonitor == 0.3 22:58:55 INFO - moztest == 0.7 22:58:55 INFO - mozversion == 1.4 22:58:55 INFO - psutil == 3.1.1 22:58:55 INFO - requests == 1.2.3 22:58:55 INFO - wsgiref == 0.1.2 22:58:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 22:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 22:58:55 INFO - Using env: {'DISPLAY': ':0', 22:58:55 INFO - 'HOME': '/home/cltbld', 22:58:55 INFO - 'LANG': 'en_US.UTF-8', 22:58:55 INFO - 'LANGUAGE': 'en_US:en', 22:58:55 INFO - 'LOGNAME': 'cltbld', 22:58:55 INFO - 'MAIL': '/var/mail/cltbld', 22:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:55 INFO - 'MOZ_NO_REMOTE': '1', 22:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:55 INFO - 'NO_EM_RESTART': '1', 22:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:55 INFO - 'PWD': '/builds/slave/test', 22:58:55 INFO - 'SHELL': '/bin/bash', 22:58:55 INFO - 'SHLVL': '1', 22:58:55 INFO - 'TERM': 'linux', 22:58:55 INFO - 'TMOUT': '86400', 22:58:55 INFO - 'USER': 'cltbld', 22:58:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:55 INFO - '_': '/tools/buildbot/bin/python'} 22:58:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:58:55 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 2)) 22:58:55 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 3)) 22:58:55 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 4)) 22:58:55 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 5)) 22:58:55 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 6)) 22:58:55 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 7)) 22:58:55 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 8)) 22:58:55 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 9)) 22:58:55 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.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:58:55 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 4)) 22:58:55 INFO - Cleaning up... 22:58:55 INFO - Return code: 0 22:58:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:58: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')]} 22:58:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58: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')]} 22:58:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 22:58:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 22:58:55 INFO - Using env: {'DISPLAY': ':0', 22:58:55 INFO - 'HOME': '/home/cltbld', 22:58:55 INFO - 'LANG': 'en_US.UTF-8', 22:58:55 INFO - 'LANGUAGE': 'en_US:en', 22:58:55 INFO - 'LOGNAME': 'cltbld', 22:58:55 INFO - 'MAIL': '/var/mail/cltbld', 22:58:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:55 INFO - 'MOZ_NO_REMOTE': '1', 22:58:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:55 INFO - 'NO_EM_RESTART': '1', 22:58:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:55 INFO - 'PWD': '/builds/slave/test', 22:58:55 INFO - 'SHELL': '/bin/bash', 22:58:55 INFO - 'SHLVL': '1', 22:58:55 INFO - 'TERM': 'linux', 22:58:55 INFO - 'TMOUT': '86400', 22:58:55 INFO - 'USER': 'cltbld', 22:58:55 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:55 INFO - '_': '/tools/buildbot/bin/python'} 22:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:58:56 INFO - Cleaning up... 22:58:56 INFO - Return code: 0 22:58:56 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:58:56 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')]} 22:58:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:58:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:56 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')]} 22:58:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:58:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:58:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--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 0x7fa9f54fff10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16378a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1704190>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, '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': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', '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 22:58:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--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 22:58:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --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 22:58:56 INFO - Using env: {'DISPLAY': ':0', 22:58:56 INFO - 'HOME': '/home/cltbld', 22:58:56 INFO - 'LANG': 'en_US.UTF-8', 22:58:56 INFO - 'LANGUAGE': 'en_US:en', 22:58:56 INFO - 'LOGNAME': 'cltbld', 22:58:56 INFO - 'MAIL': '/var/mail/cltbld', 22:58:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:58:56 INFO - 'MOZ_NO_REMOTE': '1', 22:58:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:58:56 INFO - 'NO_EM_RESTART': '1', 22:58:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:58:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:58:56 INFO - 'PWD': '/builds/slave/test', 22:58:56 INFO - 'SHELL': '/bin/bash', 22:58:56 INFO - 'SHLVL': '1', 22:58:56 INFO - 'TERM': 'linux', 22:58:56 INFO - 'TMOUT': '86400', 22:58:56 INFO - 'USER': 'cltbld', 22:58:56 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:58:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:58:56 INFO - '_': '/tools/buildbot/bin/python'} 22:58:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:58:56 INFO - Downloading/unpacking flake8 22:58:56 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 22:58:56 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 22:58:56 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 22:58:56 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 22:58:56 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 22:58:56 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 22:58:58 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:58:58 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:58:58 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 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 22:58:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:58:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:58 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:58 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:58 INFO - no previously-included directories found matching 'docs/_build' 22:58:58 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:58:58 INFO - Running setup.py install for pep8 22:58:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:58:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:58:59 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:58:59 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:58:59 INFO - no previously-included directories found matching 'docs/_build' 22:58:59 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:58:59 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:58:59 INFO - Cleaning up... 22:58:59 INFO - Return code: 0 22:58:59 INFO - Running post-action listener: _resource_record_post_action 22:58:59 INFO - Running post-action listener: _start_resource_monitoring 22:58:59 INFO - Starting resource monitoring. 22:58:59 INFO - ##### 22:58:59 INFO - ##### Finished create-virtualenv step (success) 22:58:59 INFO - ##### 22:58:59 INFO - ##### 22:58:59 INFO - ##### Running install step. 22:58:59 INFO - ##### 22:58:59 INFO - Running pre-action listener: _resource_record_pre_action 22:58:59 INFO - Running main action method: install 22:58:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:58:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:58:59 INFO - Reading from file tmpfile_stdout 22:58:59 INFO - Detecting whether we're running mozinstall >=1.0... 22:58:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:58:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:58:59 INFO - Reading from file tmpfile_stdout 22:58:59 INFO - Output received: 22:58:59 INFO - Usage: mozinstall [options] installer 22:58:59 INFO - Options: 22:58:59 INFO - -h, --help show this help message and exit 22:58:59 INFO - -d DEST, --destination=DEST 22:58:59 INFO - Directory to install application into. [default: 22:58:59 INFO - "/builds/slave/test"] 22:58:59 INFO - --app=APP Application being installed. [default: firefox] 22:58:59 INFO - mkdir: /builds/slave/test/build/application 22:58:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:58:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:59:16 INFO - Reading from file tmpfile_stdout 22:59:16 INFO - Output received: 22:59:16 INFO - /builds/slave/test/build/application/firefox/firefox 22:59:16 INFO - Running post-action listener: _resource_record_post_action 22:59:16 INFO - ##### 22:59:16 INFO - ##### Finished install step (success) 22:59:16 INFO - ##### 22:59:16 INFO - ##### 22:59:16 INFO - ##### Running run-tests step. 22:59:16 INFO - ##### 22:59:16 INFO - Running pre-action listener: _resource_record_pre_action 22:59:16 INFO - Running main action method: run_tests 22:59:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:59:16 INFO - Python 2.7.3 22:59:16 INFO - Return code: 0 22:59:16 INFO - grabbing minidump binary from tooltool 22:59:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:59:16 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1796110>, '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 0x1702230>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x17026c0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:59:16 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 22:59:16 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 22:59:16 INFO - Calling ['/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'] with output_timeout 600 22:59:16 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:59:16 INFO - Return code: 0 22:59:16 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:59:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:59:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:59:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:59:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:59:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:59:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:59:17 INFO - Return code: 0 22:59:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:59:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-025 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:59:17 INFO - Using env: {'DISPLAY': ':0', 22:59:17 INFO - 'HOME': '/home/cltbld', 22:59:17 INFO - 'LANG': 'en_US.UTF-8', 22:59:17 INFO - 'LANGUAGE': 'en_US:en', 22:59:17 INFO - 'LOGNAME': 'cltbld', 22:59:17 INFO - 'MAIL': '/var/mail/cltbld', 22:59:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:59:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:59:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:59:17 INFO - 'MOZ_NO_REMOTE': '1', 22:59:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:59:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:59:17 INFO - 'NO_EM_RESTART': '1', 22:59:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:59:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:59:17 INFO - 'PWD': '/builds/slave/test', 22:59:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:59:17 INFO - 'SHELL': '/bin/bash', 22:59:17 INFO - 'SHLVL': '1', 22:59:17 INFO - 'TERM': 'linux', 22:59:17 INFO - 'TMOUT': '86400', 22:59:17 INFO - 'USER': 'cltbld', 22:59:17 INFO - 'XDG_SESSION_COOKIE': '232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604', 22:59:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:59:17 INFO - '_': '/tools/buildbot/bin/python'} 22:59:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-025', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:59:17 INFO - mozversion application_buildid: 20160603203027 22:59:17 INFO - mozversion application_changeset: 67c37ed1a25f92cac6294cb70af97e95515c30e2 22:59:17 INFO - mozversion application_display_name: Nightly 22:59:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:59:17 INFO - mozversion application_name: Firefox 22:59:17 INFO - mozversion application_remotingname: firefox 22:59:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 22:59:17 INFO - mozversion application_vendor: Mozilla 22:59:17 INFO - mozversion application_version: 49.0a1 22:59:17 INFO - mozversion platform_buildid: 20160603203027 22:59:17 INFO - mozversion platform_changeset: 67c37ed1a25f92cac6294cb70af97e95515c30e2 22:59:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 22:59:17 INFO - mozversion platform_version: 49.0a1 22:59:17 INFO - using testdate: 1465019957 22:59:17 INFO - actual date: 1465019957 22:59:17 INFO - starting webserver on 'localhost:34217' 22:59:17 INFO - SUITE-START | Running 2 tests 22:59:17 INFO - TEST-START | dromaeo_css 22:59:17 INFO - operating with platform_type : linux_ 22:59:17 INFO - Initialising browser for dromaeo_css test... 22:59:17 INFO - TEST-INFO | started process 31646 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbMM1aA/profile http://localhost:34217/getInfo.html) 22:59:21 INFO - PROCESS | 31646 | __metrics Screen width/height:1600/1200 22:59:21 INFO - PROCESS | 31646 | colorDepth:24 22:59:21 INFO - PROCESS | 31646 | Browser inner width/height: 1024/697 22:59:21 INFO - PROCESS | 31646 | __metrics 22:59:22 ERROR - PROCESS | 31646 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:59:23 INFO - TEST-INFO | 31646: exit 0 22:59:23 INFO - Browser initialized. 22:59:23 INFO - Running cycle 1/1 for dromaeo_css test... 22:59:23 INFO - TEST-INFO | started process 31726 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpbMM1aA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:59:35 INFO - PROCESS | 31726 | RSS: Main: 155758592 22:59:35 INFO - PROCESS | 31726 | 23:00:26 INFO - PROCESS | 31726 | 1465020026256 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] 23:02:34 INFO - PROCESS | 31726 | Cycle 1(1): loaded http://localhost:34217/tests/dromaeo/cssquery-dojo.html (next: http://localhost:34217/tests/dromaeo/cssquery-ext.html) 23:02:35 INFO - PROCESS | 31726 | RSS: Main: 256548864 23:02:35 INFO - PROCESS | 31726 | 23:05:37 INFO - PROCESS | 31726 | Cycle 1(1): loaded http://localhost:34217/tests/dromaeo/cssquery-ext.html (next: http://localhost:34217/tests/dromaeo/cssquery-jquery.html) 23:05:38 INFO - PROCESS | 31726 | RSS: Main: 237441024 23:05:38 INFO - PROCESS | 31726 | 23:08:40 INFO - PROCESS | 31726 | Cycle 1(1): loaded http://localhost:34217/tests/dromaeo/cssquery-jquery.html (next: http://localhost:34217/tests/dromaeo/cssquery-mootools.html) 23:08:40 INFO - PROCESS | 31726 | RSS: Main: 241090560 23:08:40 INFO - PROCESS | 31726 | 23:11:42 INFO - PROCESS | 31726 | Cycle 1(1): loaded http://localhost:34217/tests/dromaeo/cssquery-mootools.html (next: http://localhost:34217/tests/dromaeo/cssquery-prototype.html) 23:11:43 INFO - PROCESS | 31726 | RSS: Main: 238333952 23:11:43 INFO - PROCESS | 31726 | 23:14:46 INFO - PROCESS | 31726 | Cycle 1(1): loaded http://localhost:34217/tests/dromaeo/cssquery-prototype.html (next: http://localhost:34217/tests/dromaeo/cssquery-yui.html) 23:14:46 INFO - PROCESS | 31726 | RSS: Main: 204099584 23:14:46 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | Cycle 1(1): loaded http://localhost:34217/tests/dromaeo/cssquery-yui.html (next: http://localhost:34217/tests/dromaeo/cssquery-dojo.html) 23:17:49 INFO - PROCESS | 31726 | RSS: Main: 274989056 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | __start_tp_report 23:17:49 INFO - PROCESS | 31726 | _x_x_mozilla_page_load 23:17:49 INFO - PROCESS | 31726 | _x_x_mozilla_page_load_details 23:17:49 INFO - PROCESS | 31726 | |i|pagename|runs| 23:17:49 INFO - PROCESS | 31726 | |0;dojo.html;3122.422351864905;3129.76526760493;3150.2596889730917;3181.85681644326;3193.8722451101944;5220.190870415092;5275.973620131904;5417.45825417458;5428.592855535836;5456.563474922012;5379.273798037264;5400.486953739398;5461.617686761928;5467.769751805842;5469.644473109245;4820.915294058844;4861.246506791435;4910.386201724785;4928.581070608984;5089.3638295213095;5000.774965126578;5014.5737612303;5057.645964782467;5060.493950604947;5073.670211436263;4782.402199725035;4796.25658023007;4955.405351357824;5023.472535383805;5048.14181589127;5035.395752509722;5041.571466425358;5044.747762611892;5048.9495105048845;5070;4833.589144922686;4850.708957462563;4853.781579828917;4860.416749989989;4895.632827537949;5195.116830138885;5323.361196656388;5328.92006619901;5387.865303367408;5397.028534863723;5874.706264686792;5876.08920617305;5886.352501224862;5887.5584331174905;5901.262342207224;5893.233879595668;5898.321693005317;5914.083317085816;5917.615355001911;5920.763169473216;5282.260483532308;5381.13901775714;5436.809711660112;5439.020976224238;5470.452954704498;5957.3744756799915;5974.85062873423;5995.430434108753;6009.188759517497;6017.638941663514;5417.10617748075;5770.076787713946;5781.335146458127;5781.335146458127;5783.103618939072;5716.371199168088;5775.855603609859;5789.45000224978;5789.623674461231;5811;5840.649561026267;5876.206712093898;5882.235309409835;5883.14694369314;5885.735141918538;5627.5779316551425;5776.191333213354;5794.623349482355;5842;5846;4425.358323043205;4449.822007119744;4450.977745111254;4495.078508905666;4498.932516012263;3945.546262179829;3965.4448377227213;3971.841126354971;3976.622220889011;4007.5992400760274;5367.221752845893;5404.54061404785;5485.862853428616;5592.133219351007;5660.320761508646;5800.477956983807;5803.042497987869;5828.6502809831545;5833.474987251083;5840.970795145997;5509.090999984957;5528.695921724344;5529.364123125892;5530.585206109477;5531.446855314436;4905.533974272438;4934.975325123423;4943.307936888767;4980.8754781130765;4997.825076122318;6016.548758843017;6017.879642407127;6023.186869772612;6026.5178785697035;6034.39656034393;5810.041343178328;5822.970885145547;5823.912641310299;5826.155207494805;5828.912566311594;5742.712864356682;6054.9091763624465;6101.307919489667;6111.602745821685;6198.690065496798;5847.590668653153;5851.268591426072;5853.736581853912;5856.970715146397;5898.882022359615;5628.1557766333735;5910.674912879833;5922.644641321534;5975.940240597713;5990.520758339409;5689.7439615218245;5696.287964004499;5757.193992841089;5793.94206057934;5799.4780469757925;5769.480746732815;5771.249737534094;5772.6536407815665;5773.162891380643;5777.45114214149;5377.300950876361;5532.723363831712;5636.464535869086;5681.261436013292;5686.374498805211;5648.039833228278;5779.364269930388;5781.4507621775865;5783.392743761844;5785.537157027344;5564.443555644403;5601.579881508822;5604.271444712162;5608.85977850557;5652.689102099424 23:17:49 INFO - PROCESS | 31726 | |1;ext.html;579.1602176843576;616.9722362493687;618.3785295777743;625.255945424944;629.7040391016221;32302.515462268057;35243.647563524326;35369.2926141477;35406.46890296647;42740.57259427405;45326.32010519836;45867.77066114673;46191;46270.76864615681;46326.305105423446;8674.86987695185;8883.022867484571;8884.422512536665;8885.866711999326;8890.73327800167;8725.825483490358;8862.468251904875;8863.95568022159;8864.911350886474;8866.955665221665;8840.115988401172;8845.778855528599;8875.467471951672;8894.599743011548;8912.777180570472;8755.78110547235;8826.69106581273;8834.646614135427;8835.249003834682;8837.646494140226;8702.477851328877;8780.209781119729;8781.692640757607;8796;8800.471971681656;41951.790241048904;42781.78609106935;42813.57186428127;42838.57161428377;42857.57142428598;3519.894403167909;3574.803385813779;3598.1004748812798;3641.4719865619336;3816.484774555427;32443.51334729981;32972.34055318904;33076;33303.16742081443;33340.33319333624;3992.8203230854683;4011.438398624195;4011.679065674739;4046.271671099201;4160.54234034256;346672;348053;349244;349508;349750.99999999744;34182;56712.71643641838;57575;57963.42036579664;57980.130298045566;36874.44688329677;37173.62826371756;37194.25611487755;37198.44202336967;37200;14915.179665118523;15127.092374457348;15150.772738408932;15152.31814568325;15153.393864245307;12566.623001309976;12651.557195498113;12652.051096167641;12657.303848288433;12694.492220311084;8328.541930193776;8362.12197719243;8366.121557236524;8390.202930721573;8428.45215061019;3138.8744450221734;3188.0435869239136;3196.1210667066744;3208.262099717078;3248.058063161704;1024.3085917005992;1035.00066918146;1098.423327752936;1116.6761639124563;1140.0024978144124;32882.671173288436;32968.67031329704;33049.50425743616;33163.336733265685;33686.66313336884;1116.564539829467;1120.8823073577157;1126.6056880091903;1191.0531127753457;1194.7192409783695;17115.486535403958;17411.129443527774;17452.301907923542;17493.650126997643;17539.29842806299;537710;539446;540830;542633;543732;58754.412455874895;60218;60367.69816150891;60392.396076038676;60512.394876051556;1251.2117366059506;1363.2842757552364;1390.0200358747059;1390.3256920393399;1396.3367400484892;32613;33713.66286337105;34127;34359.828200858836;34405.311893761485;50584.747076265856;50597;50616;51126.74436627793;51153.48846511636;320340;325483;325498;325987;326188;24677.259682209562;26983.73016269812;27692.307692307855;27942.3014424641;28496.57255141216;46055;46653.300200497695;46876.531234687216;47176.29235561401;47920.760396197795;38892.80553597327;39248.411273830345;39552.80223598979;39767.80116099401;39935;38790;39454.802725986185;39911;40432.3935140979;40698.389524157756;39038.4144237831;39981.40027899526;40621.79689101536;40738.79630601828;40747;35469.645303547666;35696.28607427785;36106;36136.81931590325;36385.63614363822;26722;27497.31256718518;27685;27740.44970201346;27762.042480325443 23:17:49 INFO - PROCESS | 31726 | |2;jquery.html;379.2131327495447;392.242971065843;393.3097414038362;398.852424602897;414.15099046953753;14241.430342786276;16629.50111496654;17809.82190178098;19555.90222048888;19705.605887882233;18371.265149394007;18635;18877;19021.619567608675;19128.713069303936;5471.808486702967;5572.4148964358765;5598.944010559903;5600.5799565032785;5607.299087614048;4000.0799816042327;5426.430224826396;5487.533559647435;5799.797007104752;6105.54208434367;6045.969770151165;6107.144999700045;6148.323684394713;6160.5687601867885;6161.260648722137;5913.645181289115;5968.045112781956;6106.48094911933;6115.266168059839;6121.296050954108;5180.792768289264;5402.297701298845;5408.2698835657075;5418.864528386783;5420.620556561043;22287.21994730193;22840.543189136126;22879.88560057189;23076.76923230763;23299.76700232993;2314.6528020796845;2347.7065366829147;2380.595298799208;2404;2536.8351057181226;13598.796018059737;13923.512677056357;13968.860311396858;13988.580342589637;14413;1905.1141219332937;1973.753280839895;2009.8191162795272;2017.2939471185114;2032.3801240621603;77199;79317.99999999942;79619.20380796176;80288.59855700743;80369.59815200945;9927.702168934944;9957.302988790716;10218.386896786216;10358.326708763907;10455;13914.234717090454;13927.094738841943;13932.930335348461;14057.367418466194;14057.71884562303;7926.286634202949;8069.515829050276;8184.222498862718;8251.29863961558;8263.173682631688;7927.8810817837775;8059.959700201462;8162.510249384937;8187.754367368989;8223.095459499451;6488;6527.477801775942;6636.104125943032;6650.900236496456;6665.233713066042;1832.1297383742615;1908.4370110817342;1914.8606579085701;1915.82757551821;1984.8837325398986;944.3453833663629;947.1570302430843;971.7813491964392;980.9313348065567;1002.8646132771983;18445.90777046106;18648.347307844433;18897.716534252;19028.33400830964;19587.216511339386;1014.7260239735377;1019.7399663085865;1021.7956408718137;1056.0337291378457;1084.7071290751453;10627.36235825853;11027.117830573536;11079.058280046125;11135.665930022113;11153;170525.14737426233;171775;171876;462853;479850;79579.60210198912;80485.59757201177;80640.59679701563;81109.18890811135;82067;1004.7237009822211;1051.9894801052044;1053.104860868271;1064.0423618743193;1074.8495210670512;17333.220005100055;17422.950983578077;17500.82499175043;17511.912440437718;17774.733378999583;37877.81061094677;38884.805575971935;40075.398869016404;41890.37164442475;43648.345274821535;54474;54679.453205467435;59309.517262068795;63427;63883.6805815968;8482.236598706146;8545.487270763773;8604.440711353742;8621.784455388415;9062.954685226532;9464.148226659634;10842.891571084503;12607.747845043232;13359.933200333937;13403.530876419278;11218.326900386002;11568;11743.884330988543;12091.455884485043;12211.084168687561;9713.660021899199;9973.451460169761;11114;11163.218574699693;11402.657920262405;11514.56067991501;11629.964756167807;11756;12417;12587.622371328875;8509.404341696021;8606.65573377058;8697.6955806549;8772.561371931506;8937.910620893708;10638.89361106379;11744.58893938708;11772.941135294268;12088.879111208775;12183.695407614527 23:17:49 INFO - PROCESS | 31726 | |3;mootools.html;1331.6338007048055;1491.5227127319258;1547.5666813292278;1656.1884676508507;1814.4556633010093;8385.2872505837;8872.423292485984;8880.289576833853;9095.90904090959;9129.95435022824;8877.95561022194;8946.552672366372;8985.595648195831;9043.412178208411;9086.909130908689;2105.105330234652;2345.8475199112004;2419.4919066995953;2428.8664123473245;2472.5054989002183;2451.4851881104987;2458.7172475165407;2466.2231397109945;2468.395243165427;2468.6297055441737;2393.413613664655;2454.7913427358694;2456.459578892641;2460.434100156965;2461.852288862674;2034.094827801629;2276.601594720934;2323.2449453927456;2325.5348930214027;2328.138588722167;2347.917822876191;2361;2365.6688063670927;2368.715754109518;2370.7866292033627;9012.549372531346;9053.864192037125;9066.95466522663;9068.77328066797;9072;5345.224942383333;5495.752691128869;5500.28496295479;5503.697296648683;5517.972410137963;3368.3263347330385;3454;3523.4186359250693;3537.451694987281;3619.0952261934517;1183.3077649574923;1208.4259976511175;1237.7586370657725;1255.1088727003746;1278.3161008860216;12721.491140354376;12742;12768.872311276955;12787.488500459971;12795.232286062774;4830.285248736718;4865.221564549655;4873.001034787931;4887.706737595756;4911.852644420673;10398.636047738444;10656.57373705043;10659.573617055232;10667.306625069346;10684.679459616224;5407.432219616962;5417.404085550586;5419.132938729785;5422.5119739223655;5424.755885985141;4320.330348795942;4842.878928026827;4965.577925876269;4970.428400733962;5057.570106540985;4101.630853223224;4102.118044620362;4169.8957526062095;4189.0574620710095;4201.243776120266;1281.2760790153534;1307.4900788692419;1315.7631626307166;1327.3761332174056;1334.8531661517225;969.030969030969;985.9408435493893;996.930214884951;1011.0344620887035;1027.917766578672;6128.479079278222;6132.172156758869;6157.507399408037;6243.43809057187;6295.213098362705;457.9633629309714;462.3757926798862;472.6478773313903;472.72581902497154;474.5207340586033;4073.144639625712;4104.774237416911;4180.874573762731;4305.569443055732;4306.892327691833;11320.433978301053;11375.374354410587;11376.715582110513;11529.769404611861;11536.480858361394;11286.097112231166;11307.49116289769;11335.773284534429;11365.318080915005;11365.886341136647;8067.152948940394;8283.8343233135;8321;8369.288610468057;8551.957240213758;5979.342272350125;6021.458068773832;6100.908486372619;6147.600405973598;6167;10120.291579589359;10389.272750907365;10507.527161277609;10517.21120915919;10559.04968552834;9921.40471571708;10222.795544088927;10706.089982351587;10737.248392612442;10739.516721747696;7224.2414546473965;7614.8096297593;7859.410544209093;7965.800854978672;8146.918530814616;8782.868256976279;8823.735287941372;8869.556522173994;8872.556372181236;8875.778105547413;7699.961500192463;7754.8449031017935;7794.103678077094;7860.292573668397;8129.512229266026;7770.728024519115;7860.135385107748;7892.131865494676;7924.590343618193;7994.1606131355375;7329.340359367682;7332;7432.108147022392;7440.590767507839;7449.292317229854;5159.948400516097;5176.689398636094;5204.412654140372;5277.20841873722;5281.339832520935;5150.2656411069465;5238.633295669267;5255.868603284948;5281.15501519755;5547.944520554743 23:17:49 INFO - PROCESS | 31726 | |4;prototype.html;320.5320232460609;331.72135406258747;335.5034548867677;336.34077208671005;338.7307090862765;5093.821716239932;5226.7909283628705;5301.575873930086;5341.973290133544;5382.004329199096;5306.761195746191;5323.574114070875;5330.227117068032;5355.812546560871;5357.678539287635;4197.7271477354025;4208.22147902639;4212.136512015029;4213.59970802774;4246.2356775780345;3955.683545316382;4030.758154510724;4145.253854306224;4230.661547076242;4246.8725938221905;4076.694247931402;4091.1408604193007;4128.967758060485;4129.545749967516;4146.543880173179;4109.773962432065;4140.7515549067;4141.81361838718;4153.584641535853;4197.874063778091;3466.289410670832;3687.115092377837;3695.3533131701843;3697.2051009032916;3707.7404581679293;5055.660250033744;5082.186850104003;5111.821086261963;5130.743462826844;5154.989554073877;4688.413948256468;4697.906041879178;4702.520135100036;4723.244280915037;4749.453812811536;5401.594880383947;5463.7814487420455;5499.230107784913;5506.155437928299;5523.447655234485;3945.928387596506;3962.3660214365564;3964.266610677034;3984.183242435323;3985.900352491182;5709.143628455723;5742.621770775026;5759.827205183809;5763.674354898355;5765.769369225226;3954.7824869632445;4051.7974101294817;4067.511898572131;4093.6725061995567;4102.794860256975;4052.3313653246882;4073.6944729145434;4077.429159917614;4089.427480152781;4115.938260926088;4707.317438971399;4707.8587642370785;4713.269443236235;4730.479647238801;4744.217204161344;5551.583631227674;5627.943720562824;5636.379998200195;5689.544836413077;5692;1377.4627895120911;1430.563678078196;1432.2910159471128;1433.3621538415305;1442.4086124688827;865.6104752861238;879.9032106468284;892.7544925145637;893.8078313162702;898.196114477552;4806.831760888352;4829.27560865873;4836.226203807374;4859.538343857328;4874.464543668804;4964.726940018261;5012.924806127911;5020.874478138003;5066.290719299301;5129.7178655173575;4859.684120532154;4860.3195552622665;4863.465018847924;4909.828156014522;5002.3492247558215;3002.9699703003125;3041.847907604611;3051.9623328068565;3053.9389212216074;3071.4010767900045;5957.54040260139;5966;5980.282366116094;6012.30858451275;6019.54853385989;5592.3009623797025;5790.334111577138;5793.797217097465;5852.297724273115;5863.970680146572;5886.381929897385;5928.08114742207;5932.169496270526;5932.317783454958;5961.837441698914;5637.238972738628;5743.741531631003;5778.237637520597;5842.451750286093;5847.619904706181;5677.205191273308;5722.370539240597;5735.598508104559;5735.684537350486;5742.7702891883855;5954.493868021266;5968.791092311748;5969.253843269591;5980.910286345796;6016.909746353896;3697.7411581190136;3803.8288277028155;3968.900777480586;4034.23349563582;4092.386142078712;5680.971595141998;5699.971500142473;5717.542596592346;5746.454086861741;5753.942460575341;5553.666779993213;5576.33084029919;5587.888242235052;5593.328800543961;5603.327600687943;5440.700761458158;5504.366997795305;5529.751161197675;5543.695096769555;5553.777848885999;5424.593155513273;5426.8371948841595;5455.590830687794;5473.534749546333;5474.863128421662;5393.460653934575;5576.745232322695;5602.187682785891;5616.522595579421;5621.578381621476;5452;5458.699771512605;5509.063459212024;5532.672158681807;5549.723563580318 23:17:49 INFO - PROCESS | 31726 | |5;yui.html;712.7612249896288;754.2118486181939;776.4215659333797;802.48239885274;830.5141492227046;15380;15817.920910395445;15955.361785528565;16115.919420402895;16470.917645411755;15995.120268385233;16189.433369832059;16207.594810129753;16214.189290535456;16214.351425942978;666.5067849791168;781.7459325719136;802.5626033811586;808.7331180710366;820.5774026376421;796.7848680856165;796.8884356190138;800.6157044618586;801.3428988229654;801.4830434369828;845.7040035987417;848.8132610825609;849.7493239494364;849.8342823149488;849.838530679169;698.6751160710269;716.6273537760361;717.2253965717013;811.525257724232;832.8500869843456;602.7046747093937;611.4771870051087;614.1678026274424;614.3856143856144;614.4316507230839;15338.233088345538;15402.306896189586;15409.922950385288;15439.150846703427;15445.613859653486;13075.542356017493;13173.34133293341;13478.258695771729;13490.797638035438;13640.522581709693;12727.172733772275;12744.681382965407;12907.419166137546;12913.548025819146;13222.867771322357;4460.665450091256;4462.2191116554795;4496.392986946753;4515.254982927813;4549.522300158469;79638;81967.18032819655;82531.5873420629;82719;83133;11549.076073914066;11593.826092608617;11665.300081994937;11693.766124677459;11981.64055078349;20832.583348332948;20941;21059.578808423747;21092.578148436944;21107.9446027698;8292.626831792462;8301.626426810808;8395.580220988926;8402.915970840213;8702.08628094041;4884.096442158212;4887.266909963534;4917.852464426073;4985.501449854985;5094.61790365724;3679.1721862580707;3797.715171362159;3807;3818.255440189192;3827.942580861289;645.2801441922227;645.6803882078401;645.725566634188;652.7454292825802;652.9379708927644;586.8268860686107;592.2803793390965;607.5625549604277;688.896665500179;742.4320394897908;9363.157315841607;9886.505674716236;9896.505174741234;9952.601895924083;9978.700638980843;708.1749761527865;731.8975343451921;746.2388363868823;757.651181776538;763.9847203055908;4414.249577571756;4468.240399132155;4478.910421791546;4577.542245775462;4654.37165982588;88983.55508222547;90958;91168;100269.99730002607;102828;91577;91744;92480.53759731293;93133;94555.52722236345;85567.57216214003;85635.14364856272;85654.57172714097;87473.5626321877;87600;9447.763805904908;9857.30998830075;9992.300538962494;10015.849762253427;10099.596016159565;49043.75478122586;49333.2600010993;49572;51460.74269628628;51681.224781627556;68987.65506172436;73763.63118184375;74980;76310;76982.61508692421;12801.8079728806;13031.152975056591;13387.933060334635;13391.665208369868;13449.731005379643;14100.788488172891;15014.699706006037;15149.242537872842;15316.617084572528;15935.442259520862;13679.452821887411;14003;14336.49822256216;14388.712225755216;14394;12567.43446544926;12582.622521324374;12638.936805315914;13233;13403.463861445432;13333.133346332457;13524.323783810967;13767.586972390844;13890.50568365569;13972.231527266098;10959.34243945334;11216.270942388719;11230.21388502797;11626.593069242535;12014.279143251084;11742.236754610924;11809.70475738079;11821.763564728486;11972.880271197526;12211.938940305597 23:17:49 INFO - PROCESS | 31726 | __end_tp_report 23:17:49 INFO - PROCESS | 31726 | __start_cc_report 23:17:49 INFO - PROCESS | 31726 | _x_x_mozilla_cycle_collect,807 23:17:49 INFO - PROCESS | 31726 | __end_cc_report 23:17:49 INFO - PROCESS | 31726 | __startTimestamp1465021069661__endTimestamp 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | ------- Summary: start ------- 23:17:49 INFO - PROCESS | 31726 | Number of tests: 6 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | [#0] dojo.html Cycles:165 Average:5416.04 Median:5628.16 stddev:625.68 (11.1%) stddev-sans-first:601.17 23:17:49 INFO - PROCESS | 31726 | Values: 3122.4 3129.8 3150.3 3181.9 3193.9 5220.2 5276.0 5417.5 5428.6 5456.6 5379.3 5400.5 5461.6 5467.8 5469.6 4820.9 4861.2 4910.4 4928.6 5089.4 5000.8 5014.6 5057.6 5060.5 5073.7 4782.4 4796.3 4955.4 5023.5 5048.1 5035.4 5041.6 5044.7 5048.9 5070.0 4833.6 4850.7 4853.8 4860.4 4895.6 5195.1 5323.4 5328.9 5387.9 5397.0 5874.7 5876.1 5886.4 5887.6 5901.3 5893.2 5898.3 5914.1 5917.6 5920.8 5282.3 5381.1 5436.8 5439.0 5470.5 5957.4 5974.9 5995.4 6009.2 6017.6 5417.1 5770.1 5781.3 5781.3 5783.1 5716.4 5775.9 5789.5 5789.6 5811.0 5840.6 5876.2 5882.2 5883.1 5885.7 5627.6 5776.2 5794.6 5842.0 5846.0 4425.4 4449.8 4451.0 4495.1 4498.9 3945.5 3965.4 3971.8 3976.6 4007.6 5367.2 5404.5 5485.9 5592.1 5660.3 5800.5 5803.0 5828.7 5833.5 5841.0 5509.1 5528.7 5529.4 5530.6 5531.4 4905.5 4935.0 4943.3 4980.9 4997.8 6016.5 6017.9 6023.2 6026.5 6034.4 5810.0 5823.0 5823.9 5826.2 5828.9 5742.7 6054.9 6101.3 6111.6 6198.7 5847.6 5851.3 5853.7 5857.0 5898.9 5628.2 5910.7 5922.6 5975.9 5990.5 5689.7 5696.3 5757.2 5793.9 5799.5 5769.5 5771.2 5772.7 5773.2 5777.5 5377.3 5532.7 5636.5 5681.3 5686.4 5648.0 5779.4 5781.5 5783.4 5785.5 5564.4 5601.6 5604.3 5608.9 5652.7 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | [#1] ext.html Cycles:180 Average:55828.00 Median:38841.40 stddev:110525.57 (284.6%) stddev-sans-first:110757.78 23:17:49 INFO - PROCESS | 31726 | Values: 579.2 617.0 618.4 625.3 629.7 32302.5 35243.6 35369.3 35406.5 42740.6 45326.3 45867.8 46191.0 46270.8 46326.3 8674.9 8883.0 8884.4 8885.9 8890.7 8725.8 8862.5 8864.0 8864.9 8867.0 8840.1 8845.8 8875.5 8894.6 8912.8 8755.8 8826.7 8834.6 8835.2 8837.6 8702.5 8780.2 8781.7 8796.0 8800.5 41951.8 42781.8 42813.6 42838.6 42857.6 3519.9 3574.8 3598.1 3641.5 3816.5 32443.5 32972.3 33076.0 33303.2 33340.3 3992.8 4011.4 4011.7 4046.3 4160.5 346672.0 348053.0 349244.0 349508.0 349751.0 34182.0 56712.7 57575.0 57963.4 57980.1 36874.4 37173.6 37194.3 37198.4 37200.0 14915.2 15127.1 15150.8 15152.3 15153.4 12566.6 12651.6 12652.1 12657.3 12694.5 8328.5 8362.1 8366.1 8390.2 8428.5 3138.9 3188.0 3196.1 3208.3 3248.1 1024.3 1035.0 1098.4 1116.7 1140.0 32882.7 32968.7 33049.5 33163.3 33686.7 1116.6 1120.9 1126.6 1191.1 1194.7 17115.5 17411.1 17452.3 17493.7 17539.3 537710.0 539446.0 540830.0 542633.0 543732.0 58754.4 60218.0 60367.7 60392.4 60512.4 1251.2 1363.3 1390.0 1390.3 1396.3 32613.0 33713.7 34127.0 34359.8 34405.3 50584.7 50597.0 50616.0 51126.7 51153.5 320340.0 325483.0 325498.0 325987.0 326188.0 24677.3 26983.7 27692.3 27942.3 28496.6 46055.0 46653.3 46876.5 47176.3 47920.8 38892.8 39248.4 39552.8 39767.8 39935.0 38790.0 39454.8 39911.0 40432.4 40698.4 39038.4 39981.4 40621.8 40738.8 40747.0 35469.6 35696.3 36106.0 36136.8 36385.6 26722.0 27497.3 27685.0 27740.4 27762.0 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | [#2] jquery.html Cycles:180 Average:23123.96 Median:2364.15 stddev:55156.50 (2333.0%) stddev-sans-first:55284.80 23:17:49 INFO - PROCESS | 31726 | Values: 379.2 392.2 393.3 398.9 414.2 14241.4 16629.5 17809.8 19555.9 19705.6 18371.3 18635.0 18877.0 19021.6 19128.7 5471.8 5572.4 5598.9 5600.6 5607.3 4000.1 5426.4 5487.5 5799.8 6105.5 6046.0 6107.1 6148.3 6160.6 6161.3 5913.6 5968.0 6106.5 6115.3 6121.3 5180.8 5402.3 5408.3 5418.9 5420.6 22287.2 22840.5 22879.9 23076.8 23299.8 2314.7 2347.7 2380.6 2404.0 2536.8 13598.8 13923.5 13968.9 13988.6 14413.0 1905.1 1973.8 2009.8 2017.3 2032.4 77199.0 79318.0 79619.2 80288.6 80369.6 9927.7 9957.3 10218.4 10358.3 10455.0 13914.2 13927.1 13932.9 14057.4 14057.7 7926.3 8069.5 8184.2 8251.3 8263.2 7927.9 8060.0 8162.5 8187.8 8223.1 6488.0 6527.5 6636.1 6650.9 6665.2 1832.1 1908.4 1914.9 1915.8 1984.9 944.3 947.2 971.8 980.9 1002.9 18445.9 18648.3 18897.7 19028.3 19587.2 1014.7 1019.7 1021.8 1056.0 1084.7 10627.4 11027.1 11079.1 11135.7 11153.0 170525.1 171775.0 171876.0 462853.0 479850.0 79579.6 80485.6 80640.6 81109.2 82067.0 1004.7 1052.0 1053.1 1064.0 1074.8 17333.2 17423.0 17500.8 17511.9 17774.7 37877.8 38884.8 40075.4 41890.4 43648.3 54474.0 54679.5 59309.5 63427.0 63883.7 8482.2 8545.5 8604.4 8621.8 9063.0 9464.1 10842.9 12607.7 13359.9 13403.5 11218.3 11568.0 11743.9 12091.5 12211.1 9713.7 9973.5 11114.0 11163.2 11402.7 11514.6 11630.0 11756.0 12417.0 12587.6 8509.4 8606.7 8697.7 8772.6 8937.9 10638.9 11744.6 11772.9 12088.9 12183.7 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | [#3] mootools.html Cycles:180 Average:5963.62 Median:473.62 stddev:3424.67 (723.1%) stddev-sans-first:3416.58 23:17:49 INFO - PROCESS | 31726 | Values: 1331.6 1491.5 1547.6 1656.2 1814.5 8385.3 8872.4 8880.3 9095.9 9130.0 8878.0 8946.6 8985.6 9043.4 9086.9 2105.1 2345.8 2419.5 2428.9 2472.5 2451.5 2458.7 2466.2 2468.4 2468.6 2393.4 2454.8 2456.5 2460.4 2461.9 2034.1 2276.6 2323.2 2325.5 2328.1 2347.9 2361.0 2365.7 2368.7 2370.8 9012.5 9053.9 9067.0 9068.8 9072.0 5345.2 5495.8 5500.3 5503.7 5518.0 3368.3 3454.0 3523.4 3537.5 3619.1 1183.3 1208.4 1237.8 1255.1 1278.3 12721.5 12742.0 12768.9 12787.5 12795.2 4830.3 4865.2 4873.0 4887.7 4911.9 10398.6 10656.6 10659.6 10667.3 10684.7 5407.4 5417.4 5419.1 5422.5 5424.8 4320.3 4842.9 4965.6 4970.4 5057.6 4101.6 4102.1 4169.9 4189.1 4201.2 1281.3 1307.5 1315.8 1327.4 1334.9 969.0 985.9 996.9 1011.0 1027.9 6128.5 6132.2 6157.5 6243.4 6295.2 458.0 462.4 472.6 472.7 474.5 4073.1 4104.8 4180.9 4305.6 4306.9 11320.4 11375.4 11376.7 11529.8 11536.5 11286.1 11307.5 11335.8 11365.3 11365.9 8067.2 8283.8 8321.0 8369.3 8552.0 5979.3 6021.5 6100.9 6147.6 6167.0 10120.3 10389.3 10507.5 10517.2 10559.0 9921.4 10222.8 10706.1 10737.2 10739.5 7224.2 7614.8 7859.4 7965.8 8146.9 8782.9 8823.7 8869.6 8872.6 8875.8 7700.0 7754.8 7794.1 7860.3 8129.5 7770.7 7860.1 7892.1 7924.6 7994.2 7329.3 7332.0 7432.1 7440.6 7449.3 5159.9 5176.7 5204.4 5277.2 5281.3 5150.3 5238.6 5255.9 5281.2 5547.9 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | [#4] prototype.html Cycles:180 Average:4645.74 Median:5190.89 stddev:1374.10 (26.5%) stddev-sans-first:1339.05 23:17:49 INFO - PROCESS | 31726 | Values: 320.5 331.7 335.5 336.3 338.7 5093.8 5226.8 5301.6 5342.0 5382.0 5306.8 5323.6 5330.2 5355.8 5357.7 4197.7 4208.2 4212.1 4213.6 4246.2 3955.7 4030.8 4145.3 4230.7 4246.9 4076.7 4091.1 4129.0 4129.5 4146.5 4109.8 4140.8 4141.8 4153.6 4197.9 3466.3 3687.1 3695.4 3697.2 3707.7 5055.7 5082.2 5111.8 5130.7 5155.0 4688.4 4697.9 4702.5 4723.2 4749.5 5401.6 5463.8 5499.2 5506.2 5523.4 3945.9 3962.4 3964.3 3984.2 3985.9 5709.1 5742.6 5759.8 5763.7 5765.8 3954.8 4051.8 4067.5 4093.7 4102.8 4052.3 4073.7 4077.4 4089.4 4115.9 4707.3 4707.9 4713.3 4730.5 4744.2 5551.6 5627.9 5636.4 5689.5 5692.0 1377.5 1430.6 1432.3 1433.4 1442.4 865.6 879.9 892.8 893.8 898.2 4806.8 4829.3 4836.2 4859.5 4874.5 4964.7 5012.9 5020.9 5066.3 5129.7 4859.7 4860.3 4863.5 4909.8 5002.3 3003.0 3041.8 3052.0 3053.9 3071.4 5957.5 5966.0 5980.3 6012.3 6019.5 5592.3 5790.3 5793.8 5852.3 5864.0 5886.4 5928.1 5932.2 5932.3 5961.8 5637.2 5743.7 5778.2 5842.5 5847.6 5677.2 5722.4 5735.6 5735.7 5742.8 5954.5 5968.8 5969.3 5980.9 6016.9 3697.7 3803.8 3968.9 4034.2 4092.4 5681.0 5700.0 5717.5 5746.5 5753.9 5553.7 5576.3 5587.9 5593.3 5603.3 5440.7 5504.4 5529.8 5543.7 5553.8 5424.6 5426.8 5455.6 5473.5 5474.9 5393.5 5576.7 5602.2 5616.5 5621.6 5452.0 5458.7 5509.1 5532.7 5549.7 23:17:49 INFO - PROCESS | 31726 | 23:17:49 INFO - PROCESS | 31726 | [#5] yui.html Cycles:180 Average:20324.89 Median:26965.51 stddev:28081.36 (104.1%) stddev-sans-first:28121.52 23:17:49 INFO - PROCESS | 31726 | Values: 712.8 754.2 776.4 802.5 830.5 15380.0 15817.9 15955.4 16115.9 16470.9 15995.1 16189.4 16207.6 16214.2 16214.4 666.5 781.7 802.6 808.7 820.6 796.8 796.9 800.6 801.3 801.5 845.7 848.8 849.7 849.8 849.8 698.7 716.6 717.2 811.5 832.9 602.7 611.5 614.2 614.4 614.4 15338.2 15402.3 15409.9 15439.2 15445.6 13075.5 13173.3 13478.3 13490.8 13640.5 12727.2 12744.7 12907.4 12913.5 13222.9 4460.7 4462.2 4496.4 4515.3 4549.5 79638.0 81967.2 82531.6 82719.0 83133.0 11549.1 11593.8 11665.3 11693.8 11981.6 20832.6 20941.0 21059.6 21092.6 21107.9 8292.6 8301.6 8395.6 8402.9 8702.1 4884.1 4887.3 4917.9 4985.5 5094.6 3679.2 3797.7 3807.0 3818.3 3827.9 645.3 645.7 645.7 652.7 652.9 586.8 592.3 607.6 688.9 742.4 9363.2 9886.5 9896.5 9952.6 9978.7 708.2 731.9 746.2 757.7 764.0 4414.2 4468.2 4478.9 4577.5 4654.4 88983.6 90958.0 91168.0 100270.0 102828.0 91577.0 91744.0 92480.5 93133.0 94555.5 85567.6 85635.1 85654.6 87473.6 87600.0 9447.8 9857.3 9992.3 10015.8 10099.6 49043.8 49333.3 49572.0 51460.7 51681.2 68987.7 73763.6 74980.0 76310.0 76982.6 12801.8 13031.2 13387.9 13391.7 13449.7 14100.8 15014.7 15149.2 15316.6 15935.4 13679.5 14003.0 14336.5 14388.7 14394.0 12567.4 12582.6 12638.9 13233.0 13403.5 13333.1 13524.3 13767.6 13890.5 13972.2 10959.3 11216.3 11230.2 11626.6 12014.3 11742.2 11809.7 11821.8 11972.9 12211.9 23:17:49 INFO - PROCESS | 31726 | -------- Summary: end -------- 23:17:49 INFO - PROCESS | 31726 | 23:17:50 INFO - TEST-INFO | 31726: exit 0 23:17:50 INFO - TEST-OK | dromaeo_css | took 1112922ms 23:17:50 INFO - TEST-START | kraken 23:17:50 INFO - operating with platform_type : linux_ 23:17:50 INFO - Initialising browser for kraken test... 23:17:50 INFO - TEST-INFO | started process 31907 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsILNU2/profile http://localhost:34217/getInfo.html) 23:17:54 INFO - PROCESS | 31907 | __metrics Screen width/height:1600/1200 23:17:54 INFO - PROCESS | 31907 | colorDepth:24 23:17:54 INFO - PROCESS | 31907 | Browser inner width/height: 1024/697 23:17:54 INFO - PROCESS | 31907 | __metrics 23:17:55 ERROR - PROCESS | 31907 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:17:56 INFO - TEST-INFO | 31907: exit 0 23:17:56 INFO - Browser initialized. 23:17:56 INFO - Running cycle 1/1 for kraken test... 23:17:56 INFO - TEST-INFO | started process 31986 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpsILNU2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:18:08 INFO - PROCESS | 31986 | RSS: Main: 156450816 23:18:08 INFO - PROCESS | 31986 | 23:18:10 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:34217/tests/kraken/driver.html?testName=audio-beat-detection) 23:18:11 INFO - PROCESS | 31986 | RSS: Main: 214638592 23:18:11 INFO - PROCESS | 31986 | 23:18:14 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:34217/tests/kraken/driver.html?testName=audio-dft) 23:18:14 INFO - PROCESS | 31986 | RSS: Main: 201969664 23:18:14 INFO - PROCESS | 31986 | 23:18:18 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:34217/tests/kraken/driver.html?testName=audio-fft) 23:18:18 INFO - PROCESS | 31986 | RSS: Main: 269266944 23:18:18 INFO - PROCESS | 31986 | 23:18:20 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:34217/tests/kraken/driver.html?testName=audio-oscillator) 23:18:21 INFO - PROCESS | 31986 | RSS: Main: 179785728 23:18:21 INFO - PROCESS | 31986 | 23:18:23 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:34217/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:18:24 INFO - PROCESS | 31986 | RSS: Main: 184446976 23:18:24 INFO - PROCESS | 31986 | 23:18:27 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:34217/tests/kraken/driver.html?testName=imaging-darkroom) 23:18:27 INFO - PROCESS | 31986 | RSS: Main: 220872704 23:18:27 INFO - PROCESS | 31986 | 23:18:31 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:34217/tests/kraken/driver.html?testName=imaging-desaturate) 23:18:31 INFO - PROCESS | 31986 | RSS: Main: 221462528 23:18:31 INFO - PROCESS | 31986 | 23:18:34 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:34217/tests/kraken/driver.html?testName=json-parse-financial) 23:18:35 INFO - PROCESS | 31986 | RSS: Main: 221102080 23:18:35 INFO - PROCESS | 31986 | 23:18:36 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:34217/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:18:37 INFO - PROCESS | 31986 | RSS: Main: 206962688 23:18:37 INFO - PROCESS | 31986 | 23:18:38 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:18:39 INFO - PROCESS | 31986 | RSS: Main: 211812352 23:18:39 INFO - PROCESS | 31986 | 23:18:41 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:18:42 INFO - PROCESS | 31986 | RSS: Main: 218640384 23:18:42 INFO - PROCESS | 31986 | 23:18:44 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:18:45 INFO - PROCESS | 31986 | RSS: Main: 222482432 23:18:45 INFO - PROCESS | 31986 | 23:18:48 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:18:48 INFO - PROCESS | 31986 | RSS: Main: 222158848 23:18:48 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | Cycle 1(1): loaded http://localhost:34217/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:34217/tests/kraken/driver.html?testName=ai-astar) 23:18:50 INFO - PROCESS | 31986 | RSS: Main: 264708096 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | __start_tp_report 23:18:50 INFO - PROCESS | 31986 | _x_x_mozilla_page_load 23:18:50 INFO - PROCESS | 31986 | _x_x_mozilla_page_load_details 23:18:50 INFO - PROCESS | 31986 | |i|pagename|runs| 23:18:50 INFO - PROCESS | 31986 | |0;ai-astar;112;106;111;110;112;112;113;112;112;113 23:18:50 INFO - PROCESS | 31986 | |1;audio-beat-detection;152;126;135;178;126;126;165;129;157;140 23:18:50 INFO - PROCESS | 31986 | |2;audio-dft;162;160;160;161;159;159;161;162;162;162 23:18:50 INFO - PROCESS | 31986 | |3;audio-fft;74;74;76;118;76;76;75;75;97;75 23:18:50 INFO - PROCESS | 31986 | |4;audio-oscillator;86;84;108;85;84;86;117;86;85;127 23:18:50 INFO - PROCESS | 31986 | |5;imaging-gaussian-blur;116;117;116;117;116;117;117;118;116;117 23:18:50 INFO - PROCESS | 31986 | |6;imaging-darkroom;145;146;146;146;146;146;146;146;145;146 23:18:50 INFO - PROCESS | 31986 | |7;imaging-desaturate;95;94;95;95;95;95;95;95;95;95 23:18:50 INFO - PROCESS | 31986 | |8;json-parse-financial;56;53;53;54;53;55;54;53;53;55 23:18:50 INFO - PROCESS | 31986 | |9;json-stringify-tinderbox;62;62;61;61;61;61;61;61;61;62 23:18:50 INFO - PROCESS | 31986 | |10;stanford-crypto-aes;84;79;84;78;79;84;80;80;107;81 23:18:50 INFO - PROCESS | 31986 | |11;stanford-crypto-ccm;121;120;119;129;126;119;124;134;142;122 23:18:50 INFO - PROCESS | 31986 | |12;stanford-crypto-pbkdf2;164;160;164;158;156;157;156;160;155;159 23:18:50 INFO - PROCESS | 31986 | |13;stanford-crypto-sha256-iterative;63;65;63;62;66;62;62;61;62;61 23:18:50 INFO - PROCESS | 31986 | __end_tp_report 23:18:50 INFO - PROCESS | 31986 | __start_cc_report 23:18:50 INFO - PROCESS | 31986 | _x_x_mozilla_cycle_collect,1616 23:18:50 INFO - PROCESS | 31986 | __end_cc_report 23:18:50 INFO - PROCESS | 31986 | __startTimestamp1465021130645__endTimestamp 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | ------- Summary: start ------- 23:18:50 INFO - PROCESS | 31986 | Number of tests: 14 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#0] ai-astar Cycles:10 Average:111.30 Median:112.00 stddev:2.06 (1.8%) stddev-sans-first:2.17 23:18:50 INFO - PROCESS | 31986 | Values: 112.0 106.0 111.0 110.0 112.0 112.0 113.0 112.0 112.0 113.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#1] audio-beat-detection Cycles:10 Average:143.40 Median:146.00 stddev:18.62 (12.8%) stddev-sans-first:19.49 23:18:50 INFO - PROCESS | 31986 | Values: 152.0 126.0 135.0 178.0 126.0 126.0 165.0 129.0 157.0 140.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#2] audio-dft Cycles:10 Average:160.80 Median:161.50 stddev:1.23 (0.8%) stddev-sans-first:1.22 23:18:50 INFO - PROCESS | 31986 | Values: 162.0 160.0 160.0 161.0 159.0 159.0 161.0 162.0 162.0 162.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#3] audio-fft Cycles:10 Average:81.60 Median:75.50 stddev:14.54 (19.3%) stddev-sans-first:15.16 23:18:50 INFO - PROCESS | 31986 | Values: 74.0 74.0 76.0 118.0 76.0 76.0 75.0 75.0 97.0 75.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#4] audio-oscillator Cycles:10 Average:94.80 Median:85.00 stddev:16.20 (19.1%) stddev-sans-first:16.87 23:18:50 INFO - PROCESS | 31986 | Values: 86.0 84.0 108.0 85.0 84.0 86.0 117.0 86.0 85.0 127.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.67 (0.6%) stddev-sans-first:0.67 23:18:50 INFO - PROCESS | 31986 | Values: 116.0 117.0 116.0 117.0 116.0 117.0 117.0 118.0 116.0 117.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#6] imaging-darkroom Cycles:10 Average:145.80 Median:146.00 stddev:0.42 (0.3%) stddev-sans-first:0.33 23:18:50 INFO - PROCESS | 31986 | Values: 145.0 146.0 146.0 146.0 146.0 146.0 146.0 146.0 145.0 146.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 23:18:50 INFO - PROCESS | 31986 | Values: 95.0 94.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#8] json-parse-financial Cycles:10 Average:53.90 Median:54.00 stddev:1.10 (2.0%) stddev-sans-first:0.87 23:18:50 INFO - PROCESS | 31986 | Values: 56.0 53.0 53.0 54.0 53.0 55.0 54.0 53.0 53.0 55.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#9] json-stringify-tinderbox Cycles:10 Average:61.30 Median:61.00 stddev:0.48 (0.8%) stddev-sans-first:0.44 23:18:50 INFO - PROCESS | 31986 | Values: 62.0 62.0 61.0 61.0 61.0 61.0 61.0 61.0 61.0 62.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:80.50 stddev:8.53 (10.6%) stddev-sans-first:9.04 23:18:50 INFO - PROCESS | 31986 | Values: 84.0 79.0 84.0 78.0 79.0 84.0 80.0 80.0 107.0 81.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#11] stanford-crypto-ccm Cycles:10 Average:125.60 Median:125.00 stddev:7.50 (6.0%) stddev-sans-first:7.77 23:18:50 INFO - PROCESS | 31986 | Values: 121.0 120.0 119.0 129.0 126.0 119.0 124.0 134.0 142.0 122.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:158.90 Median:159.50 stddev:3.18 (2.0%) stddev-sans-first:2.78 23:18:50 INFO - PROCESS | 31986 | Values: 164.0 160.0 164.0 158.0 156.0 157.0 156.0 160.0 155.0 159.0 23:18:50 INFO - PROCESS | 31986 | 23:18:50 INFO - PROCESS | 31986 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.70 Median:62.50 stddev:1.64 (2.6%) stddev-sans-first:1.73 23:18:50 INFO - PROCESS | 31986 | Values: 63.0 65.0 63.0 62.0 66.0 62.0 62.0 61.0 62.0 61.0 23:18:50 INFO - PROCESS | 31986 | -------- Summary: end -------- 23:18:50 INFO - PROCESS | 31986 | 23:18:51 INFO - TEST-INFO | 31986: exit 0 23:18:51 INFO - TEST-OK | kraken | took 61244ms 23:18:51 INFO - SUITE-END | took 1174s 23:18:51 INFO - Completed test suite (00:19:34) 23:18:51 INFO - javascript benchmark 23:18:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3122.422351864905, 3129.76526760493, 3150.2596889730917, 3181.85681644326, 3193.8722451101944, 5220.190870415092, 5275.973620131904, 5417.45825417458, 5428.592855535836, 5456.563474922012, 5379.273798037264, 5400.486953739398, 5461.617686761928, 5467.769751805842, 5469.644473109245, 4820.915294058844, 4861.246506791435, 4910.386201724785, 4928.581070608984, 5089.3638295213095, 5000.774965126578, 5014.5737612303, 5057.645964782467, 5060.493950604947, 5073.670211436263, 4782.402199725035, 4796.25658023007, 4955.405351357824, 5023.472535383805, 5048.14181589127, 5035.395752509722, 5041.571466425358, 5044.747762611892, 5048.9495105048845, 5070.0, 4833.589144922686, 4850.708957462563, 4853.781579828917, 4860.416749989989, 4895.632827537949, 5195.116830138885, 5323.361196656388, 5328.92006619901, 5387.865303367408, 5397.028534863723, 5874.706264686792, 5876.08920617305, 5886.352501224862, 5887.5584331174905, 5901.262342207224, 5893.233879595668, 5898.321693005317, 5914.083317085816, 5917.615355001911, 5920.763169473216, 5282.260483532308, 5381.13901775714, 5436.809711660112, 5439.020976224238, 5470.452954704498, 5957.3744756799915, 5974.85062873423, 5995.430434108753, 6009.188759517497, 6017.638941663514, 5417.10617748075, 5770.076787713946, 5781.335146458127, 5781.335146458127, 5783.103618939072, 5716.371199168088, 5775.855603609859, 5789.45000224978, 5789.623674461231, 5811.0, 5840.649561026267, 5876.206712093898, 5882.235309409835, 5883.14694369314, 5885.735141918538, 5627.5779316551425, 5776.191333213354, 5794.623349482355, 5842.0, 5846.0, 4425.358323043205, 4449.822007119744, 4450.977745111254, 4495.078508905666, 4498.932516012263, 3945.546262179829, 3965.4448377227213, 3971.841126354971, 3976.622220889011, 4007.5992400760274, 5367.221752845893, 5404.54061404785, 5485.862853428616, 5592.133219351007, 5660.320761508646, 5800.477956983807, 5803.042497987869, 5828.6502809831545, 5833.474987251083, 5840.970795145997, 5509.090999984957, 5528.695921724344, 5529.364123125892, 5530.585206109477, 5531.446855314436, 4905.533974272438, 4934.975325123423, 4943.307936888767, 4980.8754781130765, 4997.825076122318, 6016.548758843017, 6017.879642407127, 6023.186869772612, 6026.5178785697035, 6034.39656034393, 5810.041343178328, 5822.970885145547, 5823.912641310299, 5826.155207494805, 5828.912566311594, 5742.712864356682, 6054.9091763624465, 6101.307919489667, 6111.602745821685, 6198.690065496798, 5847.590668653153, 5851.268591426072, 5853.736581853912, 5856.970715146397, 5898.882022359615, 5628.1557766333735, 5910.674912879833, 5922.644641321534, 5975.940240597713, 5990.520758339409, 5689.7439615218245, 5696.287964004499, 5757.193992841089, 5793.94206057934, 5799.4780469757925, 5769.480746732815, 5771.249737534094, 5772.6536407815665, 5773.162891380643, 5777.45114214149, 5377.300950876361, 5532.723363831712, 5636.464535869086, 5681.261436013292, 5686.374498805211, 5648.039833228278, 5779.364269930388, 5781.4507621775865, 5783.392743761844, 5785.537157027344, 5564.443555644403, 5601.579881508822, 5604.271444712162, 5608.85977850557, 5652.689102099424], "value": 5373.480124450958, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [579.1602176843576, 616.9722362493687, 618.3785295777743, 625.255945424944, 629.7040391016221, 32302.515462268057, 35243.647563524326, 35369.2926141477, 35406.46890296647, 42740.57259427405, 45326.32010519836, 45867.77066114673, 46191.0, 46270.76864615681, 46326.305105423446, 8674.86987695185, 8883.022867484571, 8884.422512536665, 8885.866711999326, 8890.73327800167, 8725.825483490358, 8862.468251904875, 8863.95568022159, 8864.911350886474, 8866.955665221665, 8840.115988401172, 8845.778855528599, 8875.467471951672, 8894.599743011548, 8912.777180570472, 8755.78110547235, 8826.69106581273, 8834.646614135427, 8835.249003834682, 8837.646494140226, 8702.477851328877, 8780.209781119729, 8781.692640757607, 8796.0, 8800.471971681656, 41951.790241048904, 42781.78609106935, 42813.57186428127, 42838.57161428377, 42857.57142428598, 3519.894403167909, 3574.803385813779, 3598.1004748812798, 3641.4719865619336, 3816.484774555427, 32443.51334729981, 32972.34055318904, 33076.0, 33303.16742081443, 33340.33319333624, 3992.8203230854683, 4011.438398624195, 4011.679065674739, 4046.271671099201, 4160.54234034256, 346672.0, 348053.0, 349244.0, 349508.0, 349750.99999999744, 34182.0, 56712.71643641838, 57575.0, 57963.42036579664, 57980.130298045566, 36874.44688329677, 37173.62826371756, 37194.25611487755, 37198.44202336967, 37200.0, 14915.179665118523, 15127.092374457348, 15150.772738408932, 15152.31814568325, 15153.393864245307, 12566.623001309976, 12651.557195498113, 12652.051096167641, 12657.303848288433, 12694.492220311084, 8328.541930193776, 8362.12197719243, 8366.121557236524, 8390.202930721573, 8428.45215061019, 3138.8744450221734, 3188.0435869239136, 3196.1210667066744, 3208.262099717078, 3248.058063161704, 1024.3085917005992, 1035.00066918146, 1098.423327752936, 1116.6761639124563, 1140.0024978144124, 32882.671173288436, 32968.67031329704, 33049.50425743616, 33163.336733265685, 33686.66313336884, 1116.564539829467, 1120.8823073577157, 1126.6056880091903, 1191.0531127753457, 1194.7192409783695, 17115.486535403958, 17411.129443527774, 17452.301907923542, 17493.650126997643, 17539.29842806299, 537710.0, 539446.0, 540830.0, 542633.0, 543732.0, 58754.412455874895, 60218.0, 60367.69816150891, 60392.396076038676, 60512.394876051556, 1251.2117366059506, 1363.2842757552364, 1390.0200358747059, 1390.3256920393399, 1396.3367400484892, 32613.0, 33713.66286337105, 34127.0, 34359.828200858836, 34405.311893761485, 50584.747076265856, 50597.0, 50616.0, 51126.74436627793, 51153.48846511636, 320340.0, 325483.0, 325498.0, 325987.0, 326188.0, 24677.259682209562, 26983.73016269812, 27692.307692307855, 27942.3014424641, 28496.57255141216, 46055.0, 46653.300200497695, 46876.531234687216, 47176.29235561401, 47920.760396197795, 38892.80553597327, 39248.411273830345, 39552.80223598979, 39767.80116099401, 39935.0, 38790.0, 39454.802725986185, 39911.0, 40432.3935140979, 40698.389524157756, 39038.4144237831, 39981.40027899526, 40621.79689101536, 40738.79630601828, 40747.0, 35469.645303547666, 35696.28607427785, 36106.0, 36136.81931590325, 36385.63614363822, 26722.0, 27497.31256718518, 27685.0, 27740.44970201346, 27762.042480325443], "value": 18656.698995742245, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [379.2131327495447, 392.242971065843, 393.3097414038362, 398.852424602897, 414.15099046953753, 14241.430342786276, 16629.50111496654, 17809.82190178098, 19555.90222048888, 19705.605887882233, 18371.265149394007, 18635.0, 18877.0, 19021.619567608675, 19128.713069303936, 5471.808486702967, 5572.4148964358765, 5598.944010559903, 5600.5799565032785, 5607.299087614048, 4000.0799816042327, 5426.430224826396, 5487.533559647435, 5799.797007104752, 6105.54208434367, 6045.969770151165, 6107.144999700045, 6148.323684394713, 6160.5687601867885, 6161.260648722137, 5913.645181289115, 5968.045112781956, 6106.48094911933, 6115.266168059839, 6121.296050954108, 5180.792768289264, 5402.297701298845, 5408.2698835657075, 5418.864528386783, 5420.620556561043, 22287.21994730193, 22840.543189136126, 22879.88560057189, 23076.76923230763, 23299.76700232993, 2314.6528020796845, 2347.7065366829147, 2380.595298799208, 2404.0, 2536.8351057181226, 13598.796018059737, 13923.512677056357, 13968.860311396858, 13988.580342589637, 14413.0, 1905.1141219332937, 1973.753280839895, 2009.8191162795272, 2017.2939471185114, 2032.3801240621603, 77199.0, 79317.99999999942, 79619.20380796176, 80288.59855700743, 80369.59815200945, 9927.702168934944, 9957.302988790716, 10218.386896786216, 10358.326708763907, 10455.0, 13914.234717090454, 13927.094738841943, 13932.930335348461, 14057.367418466194, 14057.71884562303, 7926.286634202949, 8069.515829050276, 8184.222498862718, 8251.29863961558, 8263.173682631688, 7927.8810817837775, 8059.959700201462, 8162.510249384937, 8187.754367368989, 8223.095459499451, 6488.0, 6527.477801775942, 6636.104125943032, 6650.900236496456, 6665.233713066042, 1832.1297383742615, 1908.4370110817342, 1914.8606579085701, 1915.82757551821, 1984.8837325398986, 944.3453833663629, 947.1570302430843, 971.7813491964392, 980.9313348065567, 1002.8646132771983, 18445.90777046106, 18648.347307844433, 18897.716534252, 19028.33400830964, 19587.216511339386, 1014.7260239735377, 1019.7399663085865, 1021.7956408718137, 1056.0337291378457, 1084.7071290751453, 10627.36235825853, 11027.117830573536, 11079.058280046125, 11135.665930022113, 11153.0, 170525.14737426233, 171775.0, 171876.0, 462853.0, 479850.0, 79579.60210198912, 80485.59757201177, 80640.59679701563, 81109.18890811135, 82067.0, 1004.7237009822211, 1051.9894801052044, 1053.104860868271, 1064.0423618743193, 1074.8495210670512, 17333.220005100055, 17422.950983578077, 17500.82499175043, 17511.912440437718, 17774.733378999583, 37877.81061094677, 38884.805575971935, 40075.398869016404, 41890.37164442475, 43648.345274821535, 54474.0, 54679.453205467435, 59309.517262068795, 63427.0, 63883.6805815968, 8482.236598706146, 8545.487270763773, 8604.440711353742, 8621.784455388415, 9062.954685226532, 9464.148226659634, 10842.891571084503, 12607.747845043232, 13359.933200333937, 13403.530876419278, 11218.326900386002, 11568.0, 11743.884330988543, 12091.455884485043, 12211.084168687561, 9713.660021899199, 9973.451460169761, 11114.0, 11163.218574699693, 11402.657920262405, 11514.56067991501, 11629.964756167807, 11756.0, 12417.0, 12587.622371328875, 8509.404341696021, 8606.65573377058, 8697.6955806549, 8772.561371931506, 8937.910620893708, 10638.89361106379, 11744.58893938708, 11772.941135294268, 12088.879111208775, 12183.695407614527], "value": 9134.715113379536, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1331.6338007048055, 1491.5227127319258, 1547.5666813292278, 1656.1884676508507, 1814.4556633010093, 8385.2872505837, 8872.423292485984, 8880.289576833853, 9095.90904090959, 9129.95435022824, 8877.95561022194, 8946.552672366372, 8985.595648195831, 9043.412178208411, 9086.909130908689, 2105.105330234652, 2345.8475199112004, 2419.4919066995953, 2428.8664123473245, 2472.5054989002183, 2451.4851881104987, 2458.7172475165407, 2466.2231397109945, 2468.395243165427, 2468.6297055441737, 2393.413613664655, 2454.7913427358694, 2456.459578892641, 2460.434100156965, 2461.852288862674, 2034.094827801629, 2276.601594720934, 2323.2449453927456, 2325.5348930214027, 2328.138588722167, 2347.917822876191, 2361.0, 2365.6688063670927, 2368.715754109518, 2370.7866292033627, 9012.549372531346, 9053.864192037125, 9066.95466522663, 9068.77328066797, 9072.0, 5345.224942383333, 5495.752691128869, 5500.28496295479, 5503.697296648683, 5517.972410137963, 3368.3263347330385, 3454.0, 3523.4186359250693, 3537.451694987281, 3619.0952261934517, 1183.3077649574923, 1208.4259976511175, 1237.7586370657725, 1255.1088727003746, 1278.3161008860216, 12721.491140354376, 12742.0, 12768.872311276955, 12787.488500459971, 12795.232286062774, 4830.285248736718, 4865.221564549655, 4873.001034787931, 4887.706737595756, 4911.852644420673, 10398.636047738444, 10656.57373705043, 10659.573617055232, 10667.306625069346, 10684.679459616224, 5407.432219616962, 5417.404085550586, 5419.132938729785, 5422.5119739223655, 5424.755885985141, 4320.330348795942, 4842.878928026827, 4965.577925876269, 4970.428400733962, 5057.570106540985, 4101.630853223224, 4102.118044620362, 4169.8957526062095, 4189.0574620710095, 4201.243776120266, 1281.2760790153534, 1307.4900788692419, 1315.7631626307166, 1327.3761332174056, 1334.8531661517225, 969.030969030969, 985.9408435493893, 996.930214884951, 1011.0344620887035, 1027.917766578672, 6128.479079278222, 6132.172156758869, 6157.507399408037, 6243.43809057187, 6295.213098362705, 457.9633629309714, 462.3757926798862, 472.6478773313903, 472.72581902497154, 474.5207340586033, 4073.144639625712, 4104.774237416911, 4180.874573762731, 4305.569443055732, 4306.892327691833, 11320.433978301053, 11375.374354410587, 11376.715582110513, 11529.769404611861, 11536.480858361394, 11286.097112231166, 11307.49116289769, 11335.773284534429, 11365.318080915005, 11365.886341136647, 8067.152948940394, 8283.8343233135, 8321.0, 8369.288610468057, 8551.957240213758, 5979.342272350125, 6021.458068773832, 6100.908486372619, 6147.600405973598, 6167.0, 10120.291579589359, 10389.272750907365, 10507.527161277609, 10517.21120915919, 10559.04968552834, 9921.40471571708, 10222.795544088927, 10706.089982351587, 10737.248392612442, 10739.516721747696, 7224.2414546473965, 7614.8096297593, 7859.410544209093, 7965.800854978672, 8146.918530814616, 8782.868256976279, 8823.735287941372, 8869.556522173994, 8872.556372181236, 8875.778105547413, 7699.961500192463, 7754.8449031017935, 7794.103678077094, 7860.292573668397, 8129.512229266026, 7770.728024519115, 7860.135385107748, 7892.131865494676, 7924.590343618193, 7994.1606131355375, 7329.340359367682, 7332.0, 7432.108147022392, 7440.590767507839, 7449.292317229854, 5159.948400516097, 5176.689398636094, 5204.412654140372, 5277.20841873722, 5281.339832520935, 5150.2656411069465, 5238.633295669267, 5255.868603284948, 5281.15501519755, 5547.944520554743], "value": 4688.9343654044715, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [320.5320232460609, 331.72135406258747, 335.5034548867677, 336.34077208671005, 338.7307090862765, 5093.821716239932, 5226.7909283628705, 5301.575873930086, 5341.973290133544, 5382.004329199096, 5306.761195746191, 5323.574114070875, 5330.227117068032, 5355.812546560871, 5357.678539287635, 4197.7271477354025, 4208.22147902639, 4212.136512015029, 4213.59970802774, 4246.2356775780345, 3955.683545316382, 4030.758154510724, 4145.253854306224, 4230.661547076242, 4246.8725938221905, 4076.694247931402, 4091.1408604193007, 4128.967758060485, 4129.545749967516, 4146.543880173179, 4109.773962432065, 4140.7515549067, 4141.81361838718, 4153.584641535853, 4197.874063778091, 3466.289410670832, 3687.115092377837, 3695.3533131701843, 3697.2051009032916, 3707.7404581679293, 5055.660250033744, 5082.186850104003, 5111.821086261963, 5130.743462826844, 5154.989554073877, 4688.413948256468, 4697.906041879178, 4702.520135100036, 4723.244280915037, 4749.453812811536, 5401.594880383947, 5463.7814487420455, 5499.230107784913, 5506.155437928299, 5523.447655234485, 3945.928387596506, 3962.3660214365564, 3964.266610677034, 3984.183242435323, 3985.900352491182, 5709.143628455723, 5742.621770775026, 5759.827205183809, 5763.674354898355, 5765.769369225226, 3954.7824869632445, 4051.7974101294817, 4067.511898572131, 4093.6725061995567, 4102.794860256975, 4052.3313653246882, 4073.6944729145434, 4077.429159917614, 4089.427480152781, 4115.938260926088, 4707.317438971399, 4707.8587642370785, 4713.269443236235, 4730.479647238801, 4744.217204161344, 5551.583631227674, 5627.943720562824, 5636.379998200195, 5689.544836413077, 5692.0, 1377.4627895120911, 1430.563678078196, 1432.2910159471128, 1433.3621538415305, 1442.4086124688827, 865.6104752861238, 879.9032106468284, 892.7544925145637, 893.8078313162702, 898.196114477552, 4806.831760888352, 4829.27560865873, 4836.226203807374, 4859.538343857328, 4874.464543668804, 4964.726940018261, 5012.924806127911, 5020.874478138003, 5066.290719299301, 5129.7178655173575, 4859.684120532154, 4860.3195552622665, 4863.465018847924, 4909.828156014522, 5002.3492247558215, 3002.9699703003125, 3041.847907604611, 3051.9623328068565, 3053.9389212216074, 3071.4010767900045, 5957.54040260139, 5966.0, 5980.282366116094, 6012.30858451275, 6019.54853385989, 5592.3009623797025, 5790.334111577138, 5793.797217097465, 5852.297724273115, 5863.970680146572, 5886.381929897385, 5928.08114742207, 5932.169496270526, 5932.317783454958, 5961.837441698914, 5637.238972738628, 5743.741531631003, 5778.237637520597, 5842.451750286093, 5847.619904706181, 5677.205191273308, 5722.370539240597, 5735.598508104559, 5735.684537350486, 5742.7702891883855, 5954.493868021266, 5968.791092311748, 5969.253843269591, 5980.910286345796, 6016.909746353896, 3697.7411581190136, 3803.8288277028155, 3968.900777480586, 4034.23349563582, 4092.386142078712, 5680.971595141998, 5699.971500142473, 5717.542596592346, 5746.454086861741, 5753.942460575341, 5553.666779993213, 5576.33084029919, 5587.888242235052, 5593.328800543961, 5603.327600687943, 5440.700761458158, 5504.366997795305, 5529.751161197675, 5543.695096769555, 5553.777848885999, 5424.593155513273, 5426.8371948841595, 5455.590830687794, 5473.534749546333, 5474.863128421662, 5393.460653934575, 5576.745232322695, 5602.187682785891, 5616.522595579421, 5621.578381621476, 5452.0, 5458.699771512605, 5509.063459212024, 5532.672158681807, 5549.723563580318], "value": 4205.898682889962, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.7612249896288, 754.2118486181939, 776.4215659333797, 802.48239885274, 830.5141492227046, 15380.0, 15817.920910395445, 15955.361785528565, 16115.919420402895, 16470.917645411755, 15995.120268385233, 16189.433369832059, 16207.594810129753, 16214.189290535456, 16214.351425942978, 666.5067849791168, 781.7459325719136, 802.5626033811586, 808.7331180710366, 820.5774026376421, 796.7848680856165, 796.8884356190138, 800.6157044618586, 801.3428988229654, 801.4830434369828, 845.7040035987417, 848.8132610825609, 849.7493239494364, 849.8342823149488, 849.838530679169, 698.6751160710269, 716.6273537760361, 717.2253965717013, 811.525257724232, 832.8500869843456, 602.7046747093937, 611.4771870051087, 614.1678026274424, 614.3856143856144, 614.4316507230839, 15338.233088345538, 15402.306896189586, 15409.922950385288, 15439.150846703427, 15445.613859653486, 13075.542356017493, 13173.34133293341, 13478.258695771729, 13490.797638035438, 13640.522581709693, 12727.172733772275, 12744.681382965407, 12907.419166137546, 12913.548025819146, 13222.867771322357, 4460.665450091256, 4462.2191116554795, 4496.392986946753, 4515.254982927813, 4549.522300158469, 79638.0, 81967.18032819655, 82531.5873420629, 82719.0, 83133.0, 11549.076073914066, 11593.826092608617, 11665.300081994937, 11693.766124677459, 11981.64055078349, 20832.583348332948, 20941.0, 21059.578808423747, 21092.578148436944, 21107.9446027698, 8292.626831792462, 8301.626426810808, 8395.580220988926, 8402.915970840213, 8702.08628094041, 4884.096442158212, 4887.266909963534, 4917.852464426073, 4985.501449854985, 5094.61790365724, 3679.1721862580707, 3797.715171362159, 3807.0, 3818.255440189192, 3827.942580861289, 645.2801441922227, 645.6803882078401, 645.725566634188, 652.7454292825802, 652.9379708927644, 586.8268860686107, 592.2803793390965, 607.5625549604277, 688.896665500179, 742.4320394897908, 9363.157315841607, 9886.505674716236, 9896.505174741234, 9952.601895924083, 9978.700638980843, 708.1749761527865, 731.8975343451921, 746.2388363868823, 757.651181776538, 763.9847203055908, 4414.249577571756, 4468.240399132155, 4478.910421791546, 4577.542245775462, 4654.37165982588, 88983.55508222547, 90958.0, 91168.0, 100269.99730002607, 102828.0, 91577.0, 91744.0, 92480.53759731293, 93133.0, 94555.52722236345, 85567.57216214003, 85635.14364856272, 85654.57172714097, 87473.5626321877, 87600.0, 9447.763805904908, 9857.30998830075, 9992.300538962494, 10015.849762253427, 10099.596016159565, 49043.75478122586, 49333.2600010993, 49572.0, 51460.74269628628, 51681.224781627556, 68987.65506172436, 73763.63118184375, 74980.0, 76310.0, 76982.61508692421, 12801.8079728806, 13031.152975056591, 13387.933060334635, 13391.665208369868, 13449.731005379643, 14100.788488172891, 15014.699706006037, 15149.242537872842, 15316.617084572528, 15935.442259520862, 13679.452821887411, 14003.0, 14336.49822256216, 14388.712225755216, 14394.0, 12567.43446544926, 12582.622521324374, 12638.936805315914, 13233.0, 13403.463861445432, 13333.133346332457, 13524.323783810967, 13767.586972390844, 13890.50568365569, 13972.231527266098, 10959.34243945334, 11216.270942388719, 11230.21388502797, 11626.593069242535, 12014.279143251084, 11742.236754610924, 11809.70475738079, 11821.763564728486, 11972.880271197526, 12211.938940305597], "value": 7588.929550457526, "unit": "score"}], "name": "dromaeo_css", "value": 7180.53490553664, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 111.0, 110.0, 112.0, 112.0, 113.0, 112.0, 112.0, 113.0], "value": 111.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 126.0, 135.0, 178.0, 126.0, 126.0, 165.0, 129.0, 157.0, 140.0], "value": 143.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 160.0, 160.0, 161.0, 159.0, 159.0, 161.0, 162.0, 162.0, 162.0], "value": 160.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 74.0, 76.0, 118.0, 76.0, 76.0, 75.0, 75.0, 97.0, 75.0], "value": 81.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 108.0, 85.0, 84.0, 86.0, 117.0, 86.0, 85.0, 127.0], "value": 94.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 117.0, 116.0, 117.0, 116.0, 117.0, 117.0, 118.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 146.0, 145.0, 146.0], "value": 145.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 53.0, 54.0, 53.0, 55.0, 54.0, 53.0, 53.0, 55.0], "value": 53.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 61.0, 62.0], "value": 61.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 79.0, 84.0, 78.0, 79.0, 84.0, 80.0, 80.0, 107.0, 81.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 120.0, 119.0, 129.0, 126.0, 119.0, 124.0, 134.0, 142.0, 122.0], "value": 125.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 160.0, 164.0, 158.0, 156.0, 157.0, 156.0, 160.0, 155.0, 159.0], "value": 158.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 63.0, 62.0, 66.0, 62.0, 62.0, 61.0, 62.0, 61.0], "value": 62.7, "unit": "score"}], "name": "kraken", "value": 1495.3, "alertThreshold": 2.0}]} 23:18:51 INFO - Return code: 0 23:18:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 23:18:51 ERROR - # TBPL SUCCESS # 23:18:51 INFO - Running post-action listener: _resource_record_post_action 23:18:51 INFO - ##### 23:18:51 INFO - ##### Finished run-tests step (success) 23:18:51 INFO - ##### 23:18:51 INFO - Running post-run listener: _resource_record_post_run 23:18:52 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 4096; Write bytes: 540020736; Read time: 8; Write time: 531192 23:18:52 INFO - TinderboxPrint: CPU usage
13.1% 23:18:52 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 8 23:18:52 INFO - TinderboxPrint: I/O write bytes / time
540,020,736 / 531,192 23:18:52 INFO - TinderboxPrint: CPU idle
8,263.1 (86.9%) 23:18:52 INFO - TinderboxPrint: CPU user
1,204.8 (12.7%) 23:18:52 INFO - TinderboxPrint: Swap in / out
0 / 0 23:18:52 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 248508416; Read time: 0; Write time: 345228 23:18:52 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 4096; Write bytes: 291512320; Read time: 8; Write time: 185964 23:18:52 INFO - Running post-run listener: _upload_blobber_files 23:18:52 INFO - Blob upload gear active. 23:18:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:18:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 23:18:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 23:18:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 23:18:52 INFO - (blobuploader) - INFO - Open directory for files ... 23:18:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:18:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:18:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:54 INFO - (blobuploader) - INFO - Done attempting. 23:18:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 23:18:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:57 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 23:18:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:57 INFO - (blobuploader) - INFO - Done attempting. 23:18:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:18:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:18:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:18:58 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:18:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:18:58 INFO - (blobuploader) - INFO - Done attempting. 23:18:58 INFO - (blobuploader) - INFO - Iteration through files over. 23:18:58 INFO - Return code: 0 23:18:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:18:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:18:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/563e1a137cac6c236c235bd4866da641276b684b81d37c74475603a32b01f6f13c20c382e661811530603c7cc376a687ace0fe8ad0b1e94287a44e43f9bfae3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6fb87b2958c14904f2a964c838b293cf4ec58824ccc1e0deae73947864508523e9db09944ad40843c67f68fd95a6504aecf92910349aa8ff79ae30e3b78ea358", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22a06d2fec304fd779aa2b995a03ff193638d8045e98ac8252e920557bddb16e0fc2c9f0b18dd2e1cf0fba4b8b616f713e54e1521750433a3566c01e10381df5"} 23:18:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:18:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:18:58 INFO - Contents: 23:18:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/563e1a137cac6c236c235bd4866da641276b684b81d37c74475603a32b01f6f13c20c382e661811530603c7cc376a687ace0fe8ad0b1e94287a44e43f9bfae3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6fb87b2958c14904f2a964c838b293cf4ec58824ccc1e0deae73947864508523e9db09944ad40843c67f68fd95a6504aecf92910349aa8ff79ae30e3b78ea358", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22a06d2fec304fd779aa2b995a03ff193638d8045e98ac8252e920557bddb16e0fc2c9f0b18dd2e1cf0fba4b8b616f713e54e1521750433a3566c01e10381df5"} 23:18:58 INFO - Running post-run listener: copy_logs_to_upload_dir 23:18:58 INFO - Copying logs to upload dir... 23:18:58 INFO - mkdir: /builds/slave/test/build/upload/logs 23:18:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1239.320575 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 39 secs) (at 2016-06-03 23:18:58.538839) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 23:18:58.542019) ========= 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=232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/563e1a137cac6c236c235bd4866da641276b684b81d37c74475603a32b01f6f13c20c382e661811530603c7cc376a687ace0fe8ad0b1e94287a44e43f9bfae3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6fb87b2958c14904f2a964c838b293cf4ec58824ccc1e0deae73947864508523e9db09944ad40843c67f68fd95a6504aecf92910349aa8ff79ae30e3b78ea358", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22a06d2fec304fd779aa2b995a03ff193638d8045e98ac8252e920557bddb16e0fc2c9f0b18dd2e1cf0fba4b8b616f713e54e1521750433a3566c01e10381df5"} build_url:https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011424 build_url: 'https://queue.taskcluster.net/v1/task/QA-5G66oT2GDCH7QQTLHPw/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/563e1a137cac6c236c235bd4866da641276b684b81d37c74475603a32b01f6f13c20c382e661811530603c7cc376a687ace0fe8ad0b1e94287a44e43f9bfae3e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6fb87b2958c14904f2a964c838b293cf4ec58824ccc1e0deae73947864508523e9db09944ad40843c67f68fd95a6504aecf92910349aa8ff79ae30e3b78ea358", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22a06d2fec304fd779aa2b995a03ff193638d8045e98ac8252e920557bddb16e0fc2c9f0b18dd2e1cf0fba4b8b616f713e54e1521750433a3566c01e10381df5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-03 23:18:58.568352) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 23:18:58.568740) ========= 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=232498196343f89f466a4a9a000001c8-1465016405.912236-1823879604 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004421 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-03 23:18:58.653847) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 23:18:58.654220) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-03 23:18:58.654591) ========= ========= Total master_lag: 0.16 =========