builder: mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-022 starttime: 1465273765.8 results: success (0) buildid: 20160606184929 builduid: e567472d74654f07a3598ac3ad9c8279 revision: 691f37458b8cd5e51c894bfcf04afe423459aee1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.799826) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.800402) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.800713) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.815248) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.815554) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-06 21:29:26-- 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% 227M=0s 2016-06-06 21:29:26 (227 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.076716 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.905968) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.906272) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.014599 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.956186) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:25.956560) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 691f37458b8cd5e51c894bfcf04afe423459aee1 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 691f37458b8cd5e51c894bfcf04afe423459aee1 --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=9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-06 21:29:26,186 truncating revision to first 12 chars 2016-06-06 21:29:26,186 Setting DEBUG logging. 2016-06-06 21:29:26,186 attempt 1/10 2016-06-06 21:29:26,186 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/691f37458b8c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-06 21:29:26,483 unpacking tar archive at: mozilla-aurora-691f37458b8c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.649940 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:26.620108) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:26.620417) ========= script_repo_revision: 691f37458b8cd5e51c894bfcf04afe423459aee1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:26.620853) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:26.621122) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-06 21:29:26.629879) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-06-06 21:29:26.630168) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] 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=9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 21:29:26 INFO - MultiFileLogger online at 20160606 21:29:26 in /builds/slave/test 21:29:26 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 21:29:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 21:29:26 INFO - {'append_to_log': False, 21:29:26 INFO - 'base_work_dir': '/builds/slave/test', 21:29:26 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 21:29:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 21:29:26 INFO - 'branch': 'Mozilla-Aurora', 21:29:26 INFO - 'buildbot_json_path': 'buildprops.json', 21:29:26 INFO - 'config_files': ('talos/linux_config.py',), 21:29:26 INFO - 'default_actions': ('clobber', 21:29:26 INFO - 'read-buildbot-config', 21:29:26 INFO - 'download-and-extract', 21:29:26 INFO - 'populate-webroot', 21:29:26 INFO - 'create-virtualenv', 21:29:26 INFO - 'install', 21:29:26 INFO - 'run-tests'), 21:29:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 21:29:26 INFO - 'download_minidump_stackwalk': True, 21:29:26 INFO - 'download_symbols': 'ondemand', 21:29:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 21:29:26 INFO - 'tooltool.py': '/tools/tooltool.py', 21:29:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 21:29:26 INFO - '/tools/misc-python/virtualenv.py')}, 21:29:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 21:29:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 21:29:26 INFO - 'installer_path': 'installer.exe', 21:29:26 INFO - 'log_level': 'info', 21:29:26 INFO - 'log_name': 'talos', 21:29:26 INFO - 'log_to_console': True, 21:29:26 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 21:29:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 21:29:26 INFO - 'opt_config_files': (), 21:29:26 INFO - 'pip_index': False, 21:29:26 INFO - 'sps_profile': False, 21:29:26 INFO - 'sps_profile_interval': 0, 21:29:26 INFO - 'suite': 'dromaeojs-e10s', 21:29:26 INFO - 'system_bits': '32', 21:29:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 21:29:26 INFO - 'title': 'talos-linux64-ix-022', 21:29:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 21:29:26 INFO - 'use_talos_json': True, 21:29:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 21:29:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 21:29:26 INFO - 'work_dir': 'build'} 21:29:26 INFO - ##### 21:29:26 INFO - ##### Running clobber step. 21:29:26 INFO - ##### 21:29:26 INFO - Running pre-action listener: _resource_record_pre_action 21:29:26 INFO - Running main action method: clobber 21:29:26 INFO - rmtree: /builds/slave/test/build 21:29:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 21:29:27 INFO - Running post-action listener: _resource_record_post_action 21:29:27 INFO - ##### 21:29:27 INFO - ##### Finished clobber step (success) 21:29:27 INFO - ##### 21:29:27 INFO - ##### 21:29:27 INFO - ##### Running read-buildbot-config step. 21:29:27 INFO - ##### 21:29:27 INFO - Running pre-action listener: _resource_record_pre_action 21:29:27 INFO - Running main action method: read_buildbot_config 21:29:27 INFO - Using buildbot properties: 21:29:27 INFO - { 21:29:27 INFO - "project": "", 21:29:27 INFO - "product": "firefox", 21:29:27 INFO - "script_repo_revision": "production", 21:29:27 INFO - "builddir": "mozilla-aurora_ubuntu64_hw_test-dromaeojs-e10s", 21:29:27 INFO - "repository": "", 21:29:27 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-aurora pgo talos dromaeojs-e10s", 21:29:27 INFO - "buildid": "20160606184929", 21:29:27 INFO - "pgo_build": "True", 21:29:27 INFO - "basedir": "/builds/slave/test", 21:29:27 INFO - "buildnumber": 339, 21:29:27 INFO - "slavename": "talos-linux64-ix-022", 21:29:27 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 21:29:27 INFO - "platform": "ubuntu64_hw", 21:29:27 INFO - "scheduler": "tests-mozilla-aurora-linux64-pgo-talos", 21:29:27 INFO - "branch": "mozilla-aurora", 21:29:27 INFO - "repo_path": "releases/mozilla-aurora", 21:29:27 INFO - "revision": "691f37458b8cd5e51c894bfcf04afe423459aee1", 21:29:27 INFO - "stage_platform": "linux64-pgo", 21:29:27 INFO - "builduid": "e567472d74654f07a3598ac3ad9c8279", 21:29:27 INFO - "slavebuilddir": "test" 21:29:27 INFO - } 21:29:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2. 21:29:27 INFO - Running post-action listener: _resource_record_post_action 21:29:27 INFO - ##### 21:29:27 INFO - ##### Finished read-buildbot-config step (success) 21:29:27 INFO - ##### 21:29:27 INFO - ##### 21:29:27 INFO - ##### Running download-and-extract step. 21:29:27 INFO - ##### 21:29:27 INFO - Running pre-action listener: _resource_record_pre_action 21:29:27 INFO - Running main action method: download_and_extract 21:29:27 INFO - mkdir: /builds/slave/test/build/tests 21:29:27 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')]} 21:29:27 INFO - https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 21:29:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 21:29:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 21:29:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 21:29:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json'}, attempt #1 21:29:28 INFO - Downloaded 1361 bytes. 21:29:28 INFO - Reading from file /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.test_packages.json 21:29:28 INFO - Using the following test package requirements: 21:29:28 INFO - {u'common': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 21:29:28 INFO - u'cppunittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.cppunittest.tests.zip'], 21:29:28 INFO - u'gtest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.gtest.tests.zip'], 21:29:28 INFO - u'jittest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'jsshell-linux-x86_64.zip'], 21:29:28 INFO - u'mochitest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.mochitest.tests.zip'], 21:29:28 INFO - u'mozbase': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'], 21:29:28 INFO - u'reftest': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.reftest.tests.zip'], 21:29:28 INFO - u'talos': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'], 21:29:28 INFO - u'web-platform': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.web-platform.tests.zip'], 21:29:28 INFO - u'xpcshell': [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 21:29:28 INFO - u'firefox-49.0a2.en-US.linux-x86_64.xpcshell.tests.zip']} 21:29:28 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 21:29:28 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')]} 21:29:28 INFO - https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:29:28 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 21:29:28 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 21:29:28 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 21:29:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:29:29 INFO - Downloaded 24472587 bytes. 21:29:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:29:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:29:30 INFO - Return code: 0 21:29:30 INFO - Downloading packages: [u'firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 21:29:30 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')]} 21:29:30 INFO - https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 21:29:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 21:29:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 21:29:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip 21:29:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip'}, attempt #1 21:29:31 INFO - Downloaded 24472587 bytes. 21:29:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:29:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 21:29:32 INFO - Return code: 0 21:29: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')]} 21:29:32 INFO - https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 21:29:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 21:29:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 21:29:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip 21:29:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 21:29:34 INFO - Downloaded 10833369 bytes. 21:29:34 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 21:29:34 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 21:29:34 INFO - Return code: 0 21:29:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:29:34 INFO - https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 21:29:34 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 21:29:34 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 21:29:34 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 21:29:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2'}, attempt #1 21:29:39 INFO - Downloaded 63560566 bytes. 21:29:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 21:29:39 INFO - mkdir: /builds/slave/test/properties 21:29:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 21:29:39 INFO - Writing to file /builds/slave/test/properties/build_url 21:29:39 INFO - Contents: 21:29:39 INFO - build_url:https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 21:29:40 INFO - Running post-action listener: _resource_record_post_action 21:29:40 INFO - Running post-action listener: set_extra_try_arguments 21:29:40 INFO - ##### 21:29:40 INFO - ##### Finished download-and-extract step (success) 21:29:40 INFO - ##### 21:29:40 INFO - ##### 21:29:40 INFO - ##### Running populate-webroot step. 21:29:40 INFO - ##### 21:29:40 INFO - Running pre-action listener: _resource_record_pre_action 21:29:40 INFO - Running main action method: populate_webroot 21:29:40 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 21:29:40 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 21:29:40 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 21:29:40 INFO - '--tppagecycles', 21:29:40 INFO - '7'], 21:29:40 INFO - 'tests': ['tsvgm']}}, 21:29:40 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['tresize', 'tcanvasmark']}, 21:29:40 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 21:29:40 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['dromaeo_css', 'kraken']}, 21:29:40 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 21:29:40 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:40 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 21:29:40 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['damp', 'tps']}, 21:29:40 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'tests': ['damp', 'tps']}, 21:29:40 INFO - 'g3': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['dromaeo_dom']}, 21:29:40 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 21:29:40 INFO - 'other': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['a11yr', 21:29:40 INFO - 'ts_paint', 21:29:40 INFO - 'tpaint', 21:29:40 INFO - 'sessionrestore', 21:29:40 INFO - 'sessionrestore_no_auto_restore', 21:29:40 INFO - 'tabpaint']}, 21:29:40 INFO - 'other-e10s': {'tests': ['a11yr', 21:29:40 INFO - 'ts_paint', 21:29:40 INFO - 'tpaint', 21:29:40 INFO - 'sessionrestore', 21:29:40 INFO - 'sessionrestore_no_auto_restore', 21:29:40 INFO - 'tabpaint']}, 21:29:40 INFO - 'other-e10s_l64': {'tests': ['a11yr', 21:29:40 INFO - 'ts_paint', 21:29:40 INFO - 'tpaint', 21:29:40 INFO - 'sessionrestore', 21:29:40 INFO - 'sessionrestore_no_auto_restore', 21:29:40 INFO - 'tabpaint']}, 21:29:40 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 21:29:40 INFO - 'ts_paint', 21:29:40 INFO - 'tpaint', 21:29:40 INFO - 'sessionrestore', 21:29:40 INFO - 'sessionrestore_no_auto_restore', 21:29:40 INFO - 'tabpaint']}, 21:29:40 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['a11yr', 21:29:40 INFO - 'ts_paint', 21:29:40 INFO - 'tpaint', 21:29:40 INFO - 'sessionrestore', 21:29:40 INFO - 'sessionrestore_no_auto_restore', 21:29:40 INFO - 'tabpaint']}, 21:29:40 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['a11yr', 21:29:40 INFO - 'ts_paint', 21:29:40 INFO - 'tpaint', 21:29:40 INFO - 'sessionrestore', 21:29:40 INFO - 'sessionrestore_no_auto_restore', 21:29:40 INFO - 'tabpaint']}, 21:29:40 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['tsvgx', 21:29:40 INFO - 'tsvgr_opacity', 21:29:40 INFO - 'tart', 21:29:40 INFO - 'tscrollx', 21:29:40 INFO - 'cart']}, 21:29:40 INFO - 'svgr-e10s': {'tests': ['tsvgx', 21:29:40 INFO - 'tsvgr_opacity', 21:29:40 INFO - 'tart', 21:29:40 INFO - 'tscrollx', 21:29:40 INFO - 'cart']}, 21:29:40 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'talos_options': ['--disable-e10s'], 21:29:40 INFO - 'tests': ['tp5o']}, 21:29:40 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'tests': ['tp5o']}, 21:29:40 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'talos_options': ['--disable-e10s', 21:29:40 INFO - '--xperf_path', 21:29:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:40 INFO - 'tests': ['tp5n']}, 21:29:40 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 21:29:40 INFO - 'talos_options': ['--xperf_path', 21:29:40 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 21:29:40 INFO - 'tests': ['tp5n']}}, 21:29:40 INFO - 'talos.zip': {'path': '', 21:29:40 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 21:29:40 INFO - Running post-action listener: _resource_record_post_action 21:29:40 INFO - ##### 21:29:40 INFO - ##### Finished populate-webroot step (success) 21:29:40 INFO - ##### 21:29:40 INFO - ##### 21:29:40 INFO - ##### Running create-virtualenv step. 21:29:40 INFO - ##### 21:29:40 INFO - Running pre-action listener: _resource_record_pre_action 21:29:40 INFO - Running main action method: create_virtualenv 21:29:40 INFO - Creating virtualenv /builds/slave/test/build/venv 21:29:40 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 21:29:40 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 21:29:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 21:29:40 INFO - Using real prefix '/usr' 21:29:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 21:29:41 INFO - Installing distribute.............................................................................................................................................................................................done. 21:29:43 INFO - Installing pip.................done. 21:29:43 INFO - Return code: 0 21:29:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 21:29:43 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')]} 21:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:43 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')]} 21:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:43 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:43 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 21:29:43 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 21:29:43 INFO - Using env: {'DISPLAY': ':0', 21:29:43 INFO - 'HOME': '/home/cltbld', 21:29:43 INFO - 'LANG': 'en_US.UTF-8', 21:29:43 INFO - 'LANGUAGE': 'en_US:en', 21:29:43 INFO - 'LOGNAME': 'cltbld', 21:29:43 INFO - 'MAIL': '/var/mail/cltbld', 21:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:43 INFO - 'MOZ_NO_REMOTE': '1', 21:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:43 INFO - 'NO_EM_RESTART': '1', 21:29:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:43 INFO - 'PWD': '/builds/slave/test', 21:29:43 INFO - 'SHELL': '/bin/bash', 21:29:43 INFO - 'SHLVL': '1', 21:29:43 INFO - 'TERM': 'linux', 21:29:43 INFO - 'TMOUT': '86400', 21:29:43 INFO - 'USER': 'cltbld', 21:29:43 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:43 INFO - '_': '/tools/buildbot/bin/python'} 21:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:43 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 21:29:43 INFO - Cleaning up... 21:29:43 INFO - Return code: 0 21:29:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 21:29:43 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')]} 21:29:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:43 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')]} 21:29:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:43 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:43 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 21:29:43 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 21:29:43 INFO - Using env: {'DISPLAY': ':0', 21:29:43 INFO - 'HOME': '/home/cltbld', 21:29:43 INFO - 'LANG': 'en_US.UTF-8', 21:29:43 INFO - 'LANGUAGE': 'en_US:en', 21:29:43 INFO - 'LOGNAME': 'cltbld', 21:29:43 INFO - 'MAIL': '/var/mail/cltbld', 21:29:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:43 INFO - 'MOZ_NO_REMOTE': '1', 21:29:43 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:43 INFO - 'NO_EM_RESTART': '1', 21:29:43 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:43 INFO - 'PWD': '/builds/slave/test', 21:29:43 INFO - 'SHELL': '/bin/bash', 21:29:43 INFO - 'SHLVL': '1', 21:29:43 INFO - 'TERM': 'linux', 21:29:43 INFO - 'TMOUT': '86400', 21:29:43 INFO - 'USER': 'cltbld', 21:29:43 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:43 INFO - '_': '/tools/buildbot/bin/python'} 21:29:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:43 INFO - Downloading/unpacking psutil>=3.1.1 21:29:43 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 21:29:43 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 21:29:43 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 21:29:43 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 21:29:43 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 21:29:43 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 21:29:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 21:29:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:45 INFO - Installing collected packages: psutil 21:29:45 INFO - Running setup.py install for psutil 21:29:45 INFO - building 'psutil._psutil_linux' extension 21:29:45 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 21:29:45 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 21:29:45 INFO - building 'psutil._psutil_posix' extension 21:29:45 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 21:29:46 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 21:29:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 21:29:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 21:29:46 INFO - Successfully installed psutil 21:29:46 INFO - Cleaning up... 21:29:46 INFO - Return code: 0 21:29:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 21:29:46 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')]} 21:29:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:46 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')]} 21:29:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:46 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:46 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 21:29:46 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 21:29:46 INFO - Using env: {'DISPLAY': ':0', 21:29:46 INFO - 'HOME': '/home/cltbld', 21:29:46 INFO - 'LANG': 'en_US.UTF-8', 21:29:46 INFO - 'LANGUAGE': 'en_US:en', 21:29:46 INFO - 'LOGNAME': 'cltbld', 21:29:46 INFO - 'MAIL': '/var/mail/cltbld', 21:29:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:46 INFO - 'MOZ_NO_REMOTE': '1', 21:29:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:46 INFO - 'NO_EM_RESTART': '1', 21:29:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:46 INFO - 'PWD': '/builds/slave/test', 21:29:46 INFO - 'SHELL': '/bin/bash', 21:29:46 INFO - 'SHLVL': '1', 21:29:46 INFO - 'TERM': 'linux', 21:29:46 INFO - 'TMOUT': '86400', 21:29:46 INFO - 'USER': 'cltbld', 21:29:46 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:46 INFO - '_': '/tools/buildbot/bin/python'} 21:29:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 21:29:46 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 21:29:46 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 21:29:46 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 21:29:46 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 21:29:46 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 21:29:46 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 21:29:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 21:29:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 21:29:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 21:29:48 INFO - Installing collected packages: mozsystemmonitor 21:29:48 INFO - Running setup.py install for mozsystemmonitor 21:29:48 INFO - Successfully installed mozsystemmonitor 21:29:48 INFO - Cleaning up... 21:29:48 INFO - Return code: 0 21:29:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 21:29: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')]} 21:29:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29: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')]} 21:29:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:48 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:48 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 21:29:48 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 21:29:48 INFO - Using env: {'DISPLAY': ':0', 21:29:48 INFO - 'HOME': '/home/cltbld', 21:29:48 INFO - 'LANG': 'en_US.UTF-8', 21:29:48 INFO - 'LANGUAGE': 'en_US:en', 21:29:48 INFO - 'LOGNAME': 'cltbld', 21:29:48 INFO - 'MAIL': '/var/mail/cltbld', 21:29:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:48 INFO - 'MOZ_NO_REMOTE': '1', 21:29:48 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:48 INFO - 'NO_EM_RESTART': '1', 21:29:48 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:48 INFO - 'PWD': '/builds/slave/test', 21:29:48 INFO - 'SHELL': '/bin/bash', 21:29:48 INFO - 'SHLVL': '1', 21:29:48 INFO - 'TERM': 'linux', 21:29:48 INFO - 'TMOUT': '86400', 21:29:48 INFO - 'USER': 'cltbld', 21:29:48 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:48 INFO - '_': '/tools/buildbot/bin/python'} 21:29:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:48 INFO - Downloading/unpacking jsonschema==2.5.1 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 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 21:29:48 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 21:29:50 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 21:29:50 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 21:29:50 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 21:29:50 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 21:29:50 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 21:29:50 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 21:29:50 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 21:29:50 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 21:29:50 INFO - Downloading functools32-3.2.3-2.tar.gz 21:29:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 21:29:50 INFO - warning: no files found matching '*.txt' 21:29:50 INFO - no previously-included directories found matching 'build' 21:29:50 INFO - no previously-included directories found matching 'dist' 21:29:50 INFO - no previously-included directories found matching '.git*' 21:29:50 INFO - Installing collected packages: jsonschema, functools32 21:29:50 INFO - Running setup.py install for functools32 21:29:51 INFO - warning: no files found matching '*.txt' 21:29:51 INFO - no previously-included directories found matching 'build' 21:29:51 INFO - no previously-included directories found matching 'dist' 21:29:51 INFO - no previously-included directories found matching '.git*' 21:29:51 INFO - Successfully installed jsonschema functools32 21:29:51 INFO - Cleaning up... 21:29:51 INFO - Return code: 0 21:29:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 21:29: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')]} 21:29:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29: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')]} 21:29:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:51 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:51 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 21:29:51 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 21:29:51 INFO - Using env: {'DISPLAY': ':0', 21:29:51 INFO - 'HOME': '/home/cltbld', 21:29:51 INFO - 'LANG': 'en_US.UTF-8', 21:29:51 INFO - 'LANGUAGE': 'en_US:en', 21:29:51 INFO - 'LOGNAME': 'cltbld', 21:29:51 INFO - 'MAIL': '/var/mail/cltbld', 21:29:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:51 INFO - 'MOZ_NO_REMOTE': '1', 21:29:51 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:51 INFO - 'NO_EM_RESTART': '1', 21:29:51 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:51 INFO - 'PWD': '/builds/slave/test', 21:29:51 INFO - 'SHELL': '/bin/bash', 21:29:51 INFO - 'SHLVL': '1', 21:29:51 INFO - 'TERM': 'linux', 21:29:51 INFO - 'TMOUT': '86400', 21:29:51 INFO - 'USER': 'cltbld', 21:29:51 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:51 INFO - '_': '/tools/buildbot/bin/python'} 21:29:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:51 INFO - Downloading/unpacking blobuploader==1.2.4 21:29:51 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 21:29:51 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 21:29:51 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 21:29:51 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 21:29:51 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 21:29:51 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 21:29:53 INFO - Downloading blobuploader-1.2.4.tar.gz 21:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 21:29:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 21:29:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29: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 21:29:53 INFO - Downloading docopt-0.6.1.tar.gz 21:29:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 21:29:54 INFO - Installing collected packages: blobuploader, requests, docopt 21:29:54 INFO - Running setup.py install for blobuploader 21:29:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 21:29:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 21:29:54 INFO - Running setup.py install for requests 21:29:54 INFO - Running setup.py install for docopt 21:29:54 INFO - Successfully installed blobuploader requests docopt 21:29:54 INFO - Cleaning up... 21:29:54 INFO - Return code: 0 21:29:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:29:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:29:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:54 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:54 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 21:29:54 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 21:29:54 INFO - Using env: {'DISPLAY': ':0', 21:29:54 INFO - 'HOME': '/home/cltbld', 21:29:54 INFO - 'LANG': 'en_US.UTF-8', 21:29:54 INFO - 'LANGUAGE': 'en_US:en', 21:29:54 INFO - 'LOGNAME': 'cltbld', 21:29:54 INFO - 'MAIL': '/var/mail/cltbld', 21:29:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:54 INFO - 'MOZ_NO_REMOTE': '1', 21:29:54 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:54 INFO - 'NO_EM_RESTART': '1', 21:29:54 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:54 INFO - 'PWD': '/builds/slave/test', 21:29:54 INFO - 'SHELL': '/bin/bash', 21:29:54 INFO - 'SHLVL': '1', 21:29:54 INFO - 'TERM': 'linux', 21:29:54 INFO - 'TMOUT': '86400', 21:29:54 INFO - 'USER': 'cltbld', 21:29:54 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:54 INFO - '_': '/tools/buildbot/bin/python'} 21:29:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:54 INFO - Running setup.py (path:/tmp/pip-fV1K5E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:54 INFO - Running setup.py (path:/tmp/pip-Xv37E2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:55 INFO - Running setup.py (path:/tmp/pip-meydj5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:55 INFO - Running setup.py (path:/tmp/pip-ioeGWC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:55 INFO - Running setup.py (path:/tmp/pip-7TWZTV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:55 INFO - Running setup.py (path:/tmp/pip-rZxdXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:55 INFO - Running setup.py (path:/tmp/pip-xnoZY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:55 INFO - Running setup.py (path:/tmp/pip-TO1hZF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:55 INFO - Running setup.py (path:/tmp/pip-wyBiKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:55 INFO - Running setup.py (path:/tmp/pip-g1O18l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:55 INFO - Running setup.py (path:/tmp/pip-KtJPxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:55 INFO - Running setup.py (path:/tmp/pip-XupEHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:55 INFO - Running setup.py (path:/tmp/pip-O5kF1_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:55 INFO - Running setup.py (path:/tmp/pip-YSY8tp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:55 INFO - Running setup.py (path:/tmp/pip-wTzhMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:55 INFO - Running setup.py (path:/tmp/pip-aZishQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:56 INFO - Running setup.py (path:/tmp/pip-vbBfNb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 21:29:56 INFO - Running setup.py install for manifestparser 21:29:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Running setup.py install for mozcrash 21:29:56 INFO - Running setup.py install for mozdebug 21:29:56 INFO - Running setup.py install for mozdevice 21:29:56 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Running setup.py install for mozfile 21:29:56 INFO - Running setup.py install for mozhttpd 21:29:56 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Running setup.py install for mozinfo 21:29:56 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Running setup.py install for mozInstall 21:29:56 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 21:29:56 INFO - Running setup.py install for mozleak 21:29:57 INFO - Running setup.py install for mozlog 21:29:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Running setup.py install for moznetwork 21:29:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Running setup.py install for mozprocess 21:29:57 INFO - Running setup.py install for mozprofile 21:29:57 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Running setup.py install for mozrunner 21:29:57 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Running setup.py install for mozscreenshot 21:29:57 INFO - Running setup.py install for moztest 21:29:57 INFO - Running setup.py install for mozversion 21:29:57 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 21:29:57 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 21:29:57 INFO - Cleaning up... 21:29:57 INFO - Return code: 0 21:29:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:29:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:29:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:29:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:29:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:29:57 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:29:57 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 21:29:57 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 21:29:57 INFO - Using env: {'DISPLAY': ':0', 21:29:57 INFO - 'HOME': '/home/cltbld', 21:29:57 INFO - 'LANG': 'en_US.UTF-8', 21:29:57 INFO - 'LANGUAGE': 'en_US:en', 21:29:57 INFO - 'LOGNAME': 'cltbld', 21:29:57 INFO - 'MAIL': '/var/mail/cltbld', 21:29:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:29:57 INFO - 'MOZ_NO_REMOTE': '1', 21:29:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:29:57 INFO - 'NO_EM_RESTART': '1', 21:29:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:29:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:29:57 INFO - 'PWD': '/builds/slave/test', 21:29:57 INFO - 'SHELL': '/bin/bash', 21:29:57 INFO - 'SHLVL': '1', 21:29:57 INFO - 'TERM': 'linux', 21:29:57 INFO - 'TMOUT': '86400', 21:29:57 INFO - 'USER': 'cltbld', 21:29:57 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:29:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:29:57 INFO - '_': '/tools/buildbot/bin/python'} 21:29:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 21:29:58 INFO - Running setup.py (path:/tmp/pip-j1n4Zf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 21:29:58 INFO - Running setup.py (path:/tmp/pip-awYBsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 21:29:58 INFO - Running setup.py (path:/tmp/pip-wHoygk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 21:29:58 INFO - Running setup.py (path:/tmp/pip-O_uxYz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 21:29:58 INFO - Running setup.py (path:/tmp/pip-EseH0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 21:29:58 INFO - Running setup.py (path:/tmp/pip-z9IxgF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 21:29:58 INFO - Running setup.py (path:/tmp/pip-i_klQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 21:29:58 INFO - Running setup.py (path:/tmp/pip-RalDaD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 21:29:58 INFO - Running setup.py (path:/tmp/pip-YR3uu1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 21:29:58 INFO - Running setup.py (path:/tmp/pip-5tZLQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 21:29:58 INFO - Running setup.py (path:/tmp/pip-gsBXPg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 21:29:58 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)) 21:29:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 21:29:58 INFO - Running setup.py (path:/tmp/pip-71j9AC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 21:29:59 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)) 21:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 21:29:59 INFO - Running setup.py (path:/tmp/pip-y70tfx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 21:29:59 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)) 21:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 21:29:59 INFO - Running setup.py (path:/tmp/pip-9JsTxa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 21:29:59 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)) 21:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:59 INFO - Running setup.py (path:/tmp/pip-YvAxLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 21:29:59 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)) 21:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 21:29:59 INFO - Running setup.py (path:/tmp/pip-eBKmgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 21:29:59 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)) 21:29:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 21:29:59 INFO - Running setup.py (path:/tmp/pip-IVubzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 21:29:59 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)) 21:29:59 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)) 21:29:59 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)) 21:29:59 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)) 21:29:59 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)) 21:29:59 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)) 21:29:59 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 21:29:59 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 21:29:59 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 21:29:59 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 21:29:59 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 21:29:59 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 21:29:59 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 21:30:01 INFO - Downloading blessings-1.6.tar.gz 21:30:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 21:30:01 INFO - Installing collected packages: blessings 21:30:01 INFO - Running setup.py install for blessings 21:30:01 INFO - Successfully installed blessings 21:30:01 INFO - Cleaning up... 21:30:01 INFO - Return code: 0 21:30:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 21:30:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:01 INFO - Reading from file tmpfile_stdout 21:30:01 INFO - Current package versions: 21:30:01 INFO - argparse == 1.2.1 21:30:01 INFO - blessings == 1.6 21:30:01 INFO - blobuploader == 1.2.4 21:30:01 INFO - docopt == 0.6.1 21:30:01 INFO - functools32 == 3.2.3-2 21:30:01 INFO - jsonschema == 2.5.1 21:30:01 INFO - manifestparser == 1.1 21:30:01 INFO - mozInstall == 1.12 21:30:01 INFO - mozcrash == 0.17 21:30:01 INFO - mozdebug == 0.1 21:30:01 INFO - mozdevice == 0.48 21:30:01 INFO - mozfile == 1.2 21:30:01 INFO - mozhttpd == 0.7 21:30:01 INFO - mozinfo == 0.9 21:30:01 INFO - mozleak == 0.1 21:30:01 INFO - mozlog == 3.2 21:30:01 INFO - moznetwork == 0.27 21:30:01 INFO - mozprocess == 0.22 21:30:01 INFO - mozprofile == 0.28 21:30:01 INFO - mozrunner == 6.11 21:30:01 INFO - mozscreenshot == 0.1 21:30:01 INFO - mozsystemmonitor == 0.3 21:30:01 INFO - moztest == 0.7 21:30:01 INFO - mozversion == 1.4 21:30:01 INFO - psutil == 3.1.1 21:30:01 INFO - requests == 1.2.3 21:30:01 INFO - wsgiref == 0.1.2 21:30:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 21:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:01 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:30:01 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 21:30:01 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 21:30:01 INFO - Using env: {'DISPLAY': ':0', 21:30:01 INFO - 'HOME': '/home/cltbld', 21:30:01 INFO - 'LANG': 'en_US.UTF-8', 21:30:01 INFO - 'LANGUAGE': 'en_US:en', 21:30:01 INFO - 'LOGNAME': 'cltbld', 21:30:01 INFO - 'MAIL': '/var/mail/cltbld', 21:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:01 INFO - 'MOZ_NO_REMOTE': '1', 21:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:01 INFO - 'NO_EM_RESTART': '1', 21:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:01 INFO - 'PWD': '/builds/slave/test', 21:30:01 INFO - 'SHELL': '/bin/bash', 21:30:01 INFO - 'SHLVL': '1', 21:30:01 INFO - 'TERM': 'linux', 21:30:01 INFO - 'TMOUT': '86400', 21:30:01 INFO - 'USER': 'cltbld', 21:30:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:01 INFO - '_': '/tools/buildbot/bin/python'} 21:30:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 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)) 21:30:01 INFO - Cleaning up... 21:30:01 INFO - Return code: 0 21:30:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 21:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:01 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:30:01 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 21:30:01 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 21:30:01 INFO - Using env: {'DISPLAY': ':0', 21:30:01 INFO - 'HOME': '/home/cltbld', 21:30:01 INFO - 'LANG': 'en_US.UTF-8', 21:30:01 INFO - 'LANGUAGE': 'en_US:en', 21:30:01 INFO - 'LOGNAME': 'cltbld', 21:30:01 INFO - 'MAIL': '/var/mail/cltbld', 21:30:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:01 INFO - 'MOZ_NO_REMOTE': '1', 21:30:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:01 INFO - 'NO_EM_RESTART': '1', 21:30:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:01 INFO - 'PWD': '/builds/slave/test', 21:30:01 INFO - 'SHELL': '/bin/bash', 21:30:01 INFO - 'SHLVL': '1', 21:30:01 INFO - 'TERM': 'linux', 21:30:01 INFO - 'TMOUT': '86400', 21:30:01 INFO - 'USER': 'cltbld', 21:30:01 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:30:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:01 INFO - '_': '/tools/buildbot/bin/python'} 21:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:02 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 21:30:02 INFO - Cleaning up... 21:30:02 INFO - Return code: 0 21:30:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 21:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 21:30:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 21:30:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 21:30:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 21:30:02 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 0x7f10ccadaf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x19318a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x19fc350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, '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': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', '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 21:30:02 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 21:30:02 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 21:30:02 INFO - Using env: {'DISPLAY': ':0', 21:30:02 INFO - 'HOME': '/home/cltbld', 21:30:02 INFO - 'LANG': 'en_US.UTF-8', 21:30:02 INFO - 'LANGUAGE': 'en_US:en', 21:30:02 INFO - 'LOGNAME': 'cltbld', 21:30:02 INFO - 'MAIL': '/var/mail/cltbld', 21:30:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:02 INFO - 'MOZ_NO_REMOTE': '1', 21:30:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:02 INFO - 'NO_EM_RESTART': '1', 21:30:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:02 INFO - 'PWD': '/builds/slave/test', 21:30:02 INFO - 'SHELL': '/bin/bash', 21:30:02 INFO - 'SHLVL': '1', 21:30:02 INFO - 'TERM': 'linux', 21:30:02 INFO - 'TMOUT': '86400', 21:30:02 INFO - 'USER': 'cltbld', 21:30:02 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:30:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:02 INFO - '_': '/tools/buildbot/bin/python'} 21:30:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 21:30:02 INFO - Downloading/unpacking flake8 21:30:02 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 21:30:02 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 21:30:02 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 21:30:02 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 21:30:02 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 21:30:02 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 21:30:04 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 21:30:04 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 21:30:04 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 21:30:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 21:30:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 21:30:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 21:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:30:05 INFO - no previously-included directories found matching 'docs/_build' 21:30:05 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 21:30:05 INFO - Running setup.py install for pep8 21:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 21:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 21:30:05 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 21:30:05 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 21:30:05 INFO - no previously-included directories found matching 'docs/_build' 21:30:05 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 21:30:05 INFO - Successfully installed flake8 mccabe pyflakes pep8 21:30:05 INFO - Cleaning up... 21:30:05 INFO - Return code: 0 21:30:05 INFO - Running post-action listener: _resource_record_post_action 21:30:05 INFO - Running post-action listener: _start_resource_monitoring 21:30:05 INFO - Starting resource monitoring. 21:30:05 INFO - ##### 21:30:05 INFO - ##### Finished create-virtualenv step (success) 21:30:05 INFO - ##### 21:30:05 INFO - ##### 21:30:05 INFO - ##### Running install step. 21:30:05 INFO - ##### 21:30:05 INFO - Running pre-action listener: _resource_record_pre_action 21:30:05 INFO - Running main action method: install 21:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 21:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 21:30:05 INFO - Reading from file tmpfile_stdout 21:30:05 INFO - Detecting whether we're running mozinstall >=1.0... 21:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 21:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 21:30:05 INFO - Reading from file tmpfile_stdout 21:30:05 INFO - Output received: 21:30:05 INFO - Usage: mozinstall [options] installer 21:30:05 INFO - Options: 21:30:05 INFO - -h, --help show this help message and exit 21:30:05 INFO - -d DEST, --destination=DEST 21:30:05 INFO - Directory to install application into. [default: 21:30:05 INFO - "/builds/slave/test"] 21:30:05 INFO - --app=APP Application being installed. [default: firefox] 21:30:05 INFO - mkdir: /builds/slave/test/build/application 21:30:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 21:30:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 21:30:21 INFO - Reading from file tmpfile_stdout 21:30:21 INFO - Output received: 21:30:21 INFO - /builds/slave/test/build/application/firefox/firefox 21:30:21 INFO - Running post-action listener: _resource_record_post_action 21:30:21 INFO - ##### 21:30:21 INFO - ##### Finished install step (success) 21:30:21 INFO - ##### 21:30:21 INFO - ##### 21:30:21 INFO - ##### Running run-tests step. 21:30:21 INFO - ##### 21:30:21 INFO - Running pre-action listener: _resource_record_pre_action 21:30:21 INFO - Running main action method: run_tests 21:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 21:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 21:30:21 INFO - Python 2.7.3 21:30:21 INFO - Return code: 0 21:30:21 INFO - grabbing minidump binary from tooltool 21:30:21 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')]} 21:30:21 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 0x1a8b1c0>, '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 0x1a8c0c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1a8c550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 21:30:21 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 21:30:21 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 21:30:21 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 21:30:21 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 21:30:21 INFO - Return code: 0 21:30:21 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 21:30:21 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 21:30:21 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 21:30:21 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 21:30:21 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 21:30:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 21:30:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 21:30:22 INFO - Return code: 0 21:30:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 21:30:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-022 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 21:30:22 INFO - Using env: {'DISPLAY': ':0', 21:30:22 INFO - 'HOME': '/home/cltbld', 21:30:22 INFO - 'LANG': 'en_US.UTF-8', 21:30:22 INFO - 'LANGUAGE': 'en_US:en', 21:30:22 INFO - 'LOGNAME': 'cltbld', 21:30:22 INFO - 'MAIL': '/var/mail/cltbld', 21:30:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 21:30:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 21:30:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 21:30:22 INFO - 'MOZ_NO_REMOTE': '1', 21:30:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 21:30:22 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 21:30:22 INFO - 'NO_EM_RESTART': '1', 21:30:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 21:30:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 21:30:22 INFO - 'PWD': '/builds/slave/test', 21:30:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 21:30:22 INFO - 'SHELL': '/bin/bash', 21:30:22 INFO - 'SHLVL': '1', 21:30:22 INFO - 'TERM': 'linux', 21:30:22 INFO - 'TMOUT': '86400', 21:30:22 INFO - 'USER': 'cltbld', 21:30:22 INFO - 'XDG_SESSION_COOKIE': '9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153', 21:30:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 21:30:22 INFO - '_': '/tools/buildbot/bin/python'} 21:30:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-022', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 21:30:22 INFO - mozversion application_buildid: 20160606184929 21:30:22 INFO - mozversion application_changeset: 691f37458b8cd5e51c894bfcf04afe423459aee1 21:30:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 21:30:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 21:30:22 INFO - mozversion application_name: Firefox 21:30:22 INFO - mozversion application_remotingname: firefox-dev 21:30:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:30:22 INFO - mozversion application_vendor: Mozilla 21:30:22 INFO - mozversion application_version: 49.0a2 21:30:22 INFO - mozversion platform_buildid: 20160606184929 21:30:22 INFO - mozversion platform_changeset: 691f37458b8cd5e51c894bfcf04afe423459aee1 21:30:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 21:30:22 INFO - mozversion platform_version: 49.0a2 21:30:22 INFO - using testdate: 1465273822 21:30:22 INFO - actual date: 1465273822 21:30:22 INFO - starting webserver on 'localhost:54813' 21:30:22 INFO - SUITE-START | Running 2 tests 21:30:22 INFO - TEST-START | dromaeo_css 21:30:22 INFO - operating with platform_type : linux_ 21:30:22 INFO - Initialising browser for dromaeo_css test... 21:30:22 INFO - TEST-INFO | started process 24794 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp500bPS/profile http://localhost:54813/getInfo.html) 21:30:27 INFO - PROCESS | 24794 | __metrics Screen width/height:1600/1200 21:30:27 INFO - PROCESS | 24794 | colorDepth:24 21:30:27 INFO - PROCESS | 24794 | Browser inner width/height: 1024/697 21:30:27 INFO - PROCESS | 24794 | __metrics 21:30:28 ERROR - PROCESS | 24794 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 21:30:29 INFO - TEST-INFO | 24794: exit 0 21:30:29 INFO - Browser initialized. 21:30:29 INFO - Running cycle 1/1 for dromaeo_css test... 21:30:29 INFO - TEST-INFO | started process 24922 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp500bPS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:30:41 INFO - PROCESS | 24922 | RSS: Main: 156274688 21:30:41 INFO - PROCESS | 24922 | 21:31:30 INFO - PROCESS | 24922 | 1465273890781 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] 21:33:38 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:54813/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54813/tests/dromaeo/cssquery-ext.html) 21:33:39 INFO - PROCESS | 24922 | RSS: Main: 159469568 21:33:39 INFO - PROCESS | 24922 | 21:36:41 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:54813/tests/dromaeo/cssquery-ext.html (next: http://localhost:54813/tests/dromaeo/cssquery-jquery.html) 21:36:42 INFO - PROCESS | 24922 | RSS: Main: 158081024 21:36:42 INFO - PROCESS | 24922 | 21:39:44 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:54813/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54813/tests/dromaeo/cssquery-mootools.html) 21:39:44 INFO - PROCESS | 24922 | RSS: Main: 157261824 21:39:44 INFO - PROCESS | 24922 | 21:42:46 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:54813/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54813/tests/dromaeo/cssquery-prototype.html) 21:42:47 INFO - PROCESS | 24922 | RSS: Main: 157351936 21:42:47 INFO - PROCESS | 24922 | 21:45:49 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:54813/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54813/tests/dromaeo/cssquery-yui.html) 21:45:50 INFO - PROCESS | 24922 | RSS: Main: 159346688 21:45:50 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | Cycle 1(1): loaded http://localhost:54813/tests/dromaeo/cssquery-yui.html (next: http://localhost:54813/tests/dromaeo/cssquery-dojo.html) 21:48:52 INFO - PROCESS | 24922 | RSS: Main: 159657984 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | __start_tp_report 21:48:52 INFO - PROCESS | 24922 | _x_x_mozilla_page_load 21:48:52 INFO - PROCESS | 24922 | _x_x_mozilla_page_load_details 21:48:52 INFO - PROCESS | 24922 | |i|pagename|runs| 21:48:52 INFO - PROCESS | 24922 | |0;dojo.html;3398.2523844754155;3444.1389652586854;3444.3111377724463;3453.274812289419;3459.1525076356297;5747;5762.31329526998;5811.622244554106;5816.709164541777;5860.062390017598;5795.188673585691;5837.328707198673;5840.883182336362;5887.528997680186;5893.145493903381;5234.842954711356;5282.498162674539;5360.973195134019;5361.437049109846;5362.544183744387;5162.586993040566;5176.50823171798;5368.570514358861;5369.328833895763;5379.731013449332;5295.0998330283755;5297.284866543006;5303.204519322094;5320.521153096221;5344.572434205274;5323.0152421801895;5331.147016477365;5395.541378982791;5401.540869026137;5408.2698835657075;5272.947270527284;5304.814331498379;5308.150695888678;5344.599155063386;5370.5435038021815;5582.944170558324;5598.048331783566;5649.915251271234;5661.60368774186;5672.914906276409;6261.27995280544;6278.529110316699;6280.968595157041;6282.937170628326;6283.968580157116;6274;6290.811275661737;6293.213348331458;6294.7482100715915;6297.118403423525;5712.542996560265;5765.394633563457;5832.533397328161;5842.766289348463;5854.4730974212525;6376.649284289409;6415.775447859396;6422.582532135396;6434.74261029563;6468.191476065492;6160.1375807387;6161;6189.319174890758;6205.5345849061505;6228.159198508234;6020;6114.297368146176;6180.351063138396;6239.656818874914;6323.399277068763;6291.653959032297;6300.684965751786;6306.054091886253;6306.527010474233;6325.746970121236;6031.909521357183;6270.874582508415;6280.309165991737;6289.7484100635465;6307.180066591313;4717.22165842639;4723.244280915037;4775;4792.424909010941;4796.160671882407;4250.914981700349;4274.807633656493;4282.935755963663;4287.721298115613;4297.935530967038;5775.335836378787;5912.024515954819;6005.039193728982;6018;6019.849503762441;6069.393060693984;6104.694765261719;6138.570300079041;6192.195014648067;6355.872882542323;5914.940850591525;5916.940830591725;5918.556108291896;5924.052151655801;5926.318473375531;5149.922751158736;5329.25390445338;5338.813141540028;5346.679199248058;5366.409694933554;6427.164468619049;6581.2760596334365;6586.80239592813;6590.9670451647435;6592.835179120464;6334.556581039283;6340.746370145235;6357.618542887441;6360.014197799349;6373.075903993988;6259.217597800275;6419.293877673359;6463.418292353523;6556.475481961527;6593.175853018372;6233.501319894307;6259.561830671809;6262.029385445263;6269.529785266214;6276.9372306276355;6245.313015568193;6276.937230627818;6295.150154729236;6298.401651843067;6303.401176888188;5917.556183286185;6020.307664618625;6062.7271772769445;6075.605085669417;6170.7223174958135;6062.18160548338;6093.786717464867;6101.969490152521;6105.389461053859;6132.785352512819;5957.314908785372;5965.28416590012;5993.4006599341465;6008.489278411209;6014.278286605635;5947.9702601486715;6088.908666370096;6109.991851344478;6150.477209437248;6239.656818875005;6083.148359229623;6120.938790612036;6122.957601858128;6125.295591007091;6131.729353333239 21:48:52 INFO - PROCESS | 24922 | |1;ext.html;596.2606368103553;640.7885397818719;657.3131078023468;670.4033410264864;681.6182937554967;41647.58352416475;45645.77177114118;45736.77131614346;45798.77100614496;45867.77066114665;46329.53670463294;46729;46848.29727554089;46985.295220571716;47017.29474057892;9075.591598378089;9225.769355766126;9253.444793312423;9268.629254829799;9274.721758347227;9048.321375896801;9110.544472776368;9151.496667683276;9154.588043538024;9158.542072896362;9101.863472047924;9187.35688501805;9207.585658645343;9224.262059035294;9227.630894764201;9011.594478248462;9073.727788166365;9143;9167.862482062774;9179.17387435134;8970.461772293618;9141.177294043502;9175.082491750838;9177.908220917772;9208;42508.14983700309;42828;42870.35694464586;42884.1423171538;42884.57115428868;3568.286342731464;3675.0996005978704;3820.8089595520114;3891.5135608048995;4329.307310830252;34245.14387140317;34264.657353426395;34411.31177376463;34504.137396565035;35299.4705079424;4442.400275962736;4443.142667077033;4481.843135490275;4488.910221795546;4546.272596384596;368243;368253;368378.9999999973;368770;370619;37612;56469.43530564724;56554.7172264136;56896;57147.71426142926;37907.43138852919;40340.59659403427;40469.797651012144;40540.39189412161;40834.79582602068;17313.82686173122;17515.38696145654;17548.82451175472;17601.11994400275;17619.91190044067;14429.422823087169;14453.566393008226;14476.276186190858;14553.345099470338;14568.198749068692;9211.953940230256;9216.262698984065;9250.90749092514;9280.95359523198;9347.626094956126;3522.982385088058;3530.3468858261303;3560.857565697343;3583.157957879896;3621.0766254604905;1037.9221558383151;1080.4651697409834;1153.1236260441956;1159.5245949160972;1181.6868529839599;32680.673193268238;33238.16904577357;33363.165920852174;33444.829430969796;33600.831995839864;1149.5804031528432;1169.8771628978834;1221.248931906871;1232.6979889927024;1302.2121616421928;17344.65310693779;17468.38860639897;17522.12389380526;17623.030733309533;18879.716804247946;587818;590189;608423;916902;923452;59581;60035.399646003854;60317.396826032054;60834.39165608287;60840;1481.4740767027765;1488.4343949299198;1489.3744627256578;1517.0594231576492;1522.5127959052998;31344.68655313509;31830.84084579562;32001.199970000005;32440.188995275308;32598;51929.480705192465;52025.7398713004;52590.47409526009;52635.21047184219;52704.736476317375;328280;332726;332804;333113;333447;28929.42141157207;29176.124716258546;29199.124026279245;29285.26786830261;29286.707132928397;48393.03213935772;48753;48928;49481.752591238255;49566.50433495763;40850;41088;41800.37299440572;42025.369619456345;42639.360409594505;40848;41628.41194142332;42150.36774448324;42496.362554561085;42809;41655.37516937188;41723.37414938718;41941.58058419377;42696.57303426926;42904.57095429006;36838;37203.44194837025;37738;37766.811165944;37882.741519441195;28605.28486787847;28857.278568035967;28939;29064.273393164494;29091.563626545194 21:48:52 INFO - PROCESS | 24922 | |2;jquery.html;400.6954714417042;405.1491867079136;407.15108997739713;408.5505943462192;416.56885123896774;18486.630267394645;20259.088341024653;20508;20524;21403.143874245052;19860.40418787439;20102.597948041068;20606.587868242663;20688.2759103431;20734.688979665316;5439.564834813215;5710.942890571104;5723.51350135239;5741.655500669952;5768;5586.692731899764;5733.140028995643;5744.59787814851;5745.6552606843725;5795.652260864341;5706.942930570683;5720.056190728525;5742.368339482654;5747.166660834177;5762.884742305151;5561.5272701820395;5686.601937864351;5696.487316141546;5720.742566584493;5763.077907534816;5102.6428150029515;5116.795328186868;5157.896842063175;5178.637495375326;5179.792808287664;17795.110244487725;18215.817841821543;18743.531411714815;22207.44481387962;23073;2452.31335226137;2500.8999640014376;2566.8959959407143;2743.684476285233;2853.2296280004284;15112;15523.534293971199;15548.300326485336;15579.844201557953;15676.451324203597;2040.540878302385;2089.8014688604558;2111.0816794694356;2146.8389870759597;2154.590627780732;81676.59161704213;84501;84547;84889.15110848875;85427.57286213592;10611.946940265403;10640.680779576625;10936.617218397318;10953.123750099972;11181.273217241016;14708.191049492167;14803.555893323217;14824;14857;14900.329485173193;8790.296776257885;8997.415168014059;9010.50442225684;9114.908850911406;9187.724368268962;9090.36367454272;9100.180983711498;9228.676996305097;9250.583723732449;9369;6917.584944903322;6936.716707409045;6955.89566156508;6970.198427180838;7019.5086343956455;1962.2641509433963;1989.303743689697;1991.29309095272;2010.7587089549347;2023.1401654296865;1035.502958579886;1084.5986984815668;1143.7026373142876;1170.7073231692077;1177.1936223686803;18921;19783.20867165326;20287.18851245963;20379.388618341472;20937;1066.7866426714688;1089.629525961177;1096.3531516405199;1100.7358234023777;1157.328749325406;11516.251443656136;11598.942005290088;11761.411929403495;11993;11995.820062699066;509901;527651;531723;532590;536762;84568.57715711382;86846;86931.56534217288;87006.12993870107;90587.0041857689;1087.6465148826505;1134.211722852626;1153.8153895376868;1155.9595414160465;1161.3032180691519;18270.63458730791;18377;18478.81521184771;18758.812411875708;18782.060896955372;32110;39057.218855623294;40229.1954160921;41444.58555414528;41764.1647167061;53756;54972.45027549673;55861.16208256798;55923.440765591826;56481.71759141178;8671.69649062305;8726.91273087261;8836.867446988172;8885.511296878734;9051.683191088543;13925;14055.367508461939;14098.647533811736;14116.858831411555;14504.202268875313;12214.328211948428;12254.938725306316;12351.814722778985;12611;12658.177218480769;11665.416729163338;12360.196587221802;12418.503259869503;12561.623151305475;12605.054620903653;12419.503219871465;12497.25016499013;12609.68475788076;12696.111272210857;12816.295103769382;8741.956290218508;8855.867161992446;8885.378023538291;9025.774355641162;9076.27389808827;12138.514459421523;12376.381180940736;12649.747005059664;12823.230606163661;12885.677858053623 21:48:52 INFO - PROCESS | 24922 | |3;mootools.html;1600.2798740566743;1613.9112348820818;1774.8047714751376;1905.7236700678393;1907.2847682119204;9114.954425227872;9639;9764.170045546129;9785.755356116091;10043.799124017516;9404.059594040593;9607.135357817795;9644.469554174517;9695.078967498093;9708.854367184498;2567.1785028790746;2586.2370600672753;2604.4270260542744;2608.5695860183046;2610.464854704781;2566.5251928393213;2644.2860427684514;2645.7486538778785;2648.72188420216;2653.9071132510367;2521.798256139514;2553.48930213957;2557.424579469623;2560.0143944581378;2561.8334808237496;2534.277730846709;2547.8598677072755;2554.8211625186245;2568.460623269116;2579.6646435963203;2466.9136580219606;2492.9654193509673;2502.749725027501;2576.497582971321;2579.974200258011;9680.419174849532;9753;9762.609495620238;9789.21686265097;9803.215742740564;5796.652200867961;5836.270466191726;5891.469767720883;5899.410058994109;5944.7324870380935;3631.1466805300997;3633.218857945554;3733.4213196954515;3788;3810.466534685146;1112.57165991094;1133.6372360844532;1150.8734039255676;1157.7785436364632;1171.5079666540073;12921.806172907414;13024.804627930589;13039.674008149777;13133.474661013644;13134.211947283193;4838.491958344323;4888.120138375056;5044.117279476077;5045.2432135180015;5059.139946209153;10373.481325933673;10377.792444151075;10426.947865260625;10432.634857779942;10449.634262800766;5652.208690783294;5655.066913959233;5683.687397193194;5714.714264286769;5723.856903577361;4584.174848527297;5278.683279003195;5298.337707786527;5346.625736198429;5360.303160589098;4053.777042262645;4109.589041095866;4168.083021735213;4180.498340199196;4187.7443808020735;1187.400362816772;1195.8266051422495;1203.4283715235367;1211.7576484703095;1212.1272683667746;1089.4389389464432;1102.0412241349916;1102.6636875752813;1103.9834402483968;1111.74985628233;6525.575837570543;6641.468682505388;6719.227288861745;6771.356721111487;6806.455483561403;473.78916382210275;479.5132940065836;482.92031814750874;495.24475175357867;502.53264464048783;4477.865664030019;4508.571685689854;4517.34498497716;4519.163954668397;4538.205813982539;11804.88195118055;12228.144029917821;12263.693407664701;12327.321997290055;12354.876451235552;11648;12068.637940861789;12109.091818113677;12257.325847078318;12452.128351015523;9003.189712925869;9098.135677110664;9103.544822758968;9114.088591140833;9119.179273865384;6719.294474080151;6788.321167883172;6896.27589103137;6938.895916561155;7059.047028651272;11141.777164456504;11218.887811121784;11239.157063220455;11303.773924521627;11384.430778460877;11411.714707132389;11442.656720298404;11480.425978701198;11552.537898483966;11577.594784182513;8380.455270407405;8599.14008599135;8689.13108689126;8735.56322183901;8743.956280218557;9444.905550944402;9543.809123817624;9550.235981121356;9646.228301735984;9661.806763864824;8338.958305208434;8644.308455323433;8691.348148888985;8711.69509067205;8728.607212675317;8267.214614611843;8346.374021948257;8408.369372297224;8457.40798144124;8529.104444033288;7506.474546781673;7518.511296765692;7590.506617069873;7598.886016709865;7612.733554325572;5650.717464126859;5841.2406387171095;5889.5582831286965;5925.762969481172;5942.435468630473;5650;5770.567207459541;5834.212381328466;5938.376470470538;5940 21:48:52 INFO - PROCESS | 24922 | |4;prototype.html;354.9751517393782;362.4092728851972;362.48708078069524;370.679362351566;374.52061361457345;5655.179998900157;5741.712914354286;5926.822195334142;5930.2883653961535;5948.286205655317;5753.971230143843;5756.654600723959;5757.877213943283;5770.9422905770925;5776.4223577642215;4407;4479.977600111994;4481.4846292676275;4511.548845115479;4515.300128480075;4360.825566977317;4441.067375851075;4454.797204754715;4461.88845278869;4491.034427598066;4375.7593332366705;4390.714603550775;4399.340098985162;4422.402975598301;4467.955320446803;4441.75570343631;4445.1331990261915;4462.598366147046;4473.686841921067;4481.148581769486;3876.3604005339084;3879.301725689375;3919.6477215360655;3957.584453632356;3966.127451960564;5564.443555644443;5609.803656872031;5614.831555053355;5619.831405057855;5641.943580564182;5156.974215128937;5178.032347707847;5178.093833579109;5180.6891586504935;5182.585393168538;5968.940310596882;6080.118382834465;6089.203684912962;6133.877322453526;6137.631742095488;4330.783460826946;4335.869923902288;4344.891377715582;4377.124575084996;4378.6934914555995;6380.904286435707;6382.840428989266;6391.616503009788;6398.328175541595;6418.839529011765;4177.811998460016;4415.470143582791;4437.42313499243;4448.933266001012;4456.9331460028125;4481.462224533077;4484.12559550891;4503.166914120898;4507.256302710016;4507.864764057083;5155.226715992556;5156.226566015128;5161.380634323905;5169.7932082717025;5196.32447781786;5847.8245652630485;5874.32445268791;6074.086662701235;6121.632702037892;6132.233470816148;1549.0008944231133;1549.9767503487458;1567.4592265668325;1571.1908367190906;1573.3156077106496;972.154225823538;979.1970584121095;988.4020167798378;996.6511720897773;1007.2596641468515;5247.1866860636665;5288.83157905257;5297.867553311198;5306.495882891119;5347.379783905858;5566.304211973504;5595.922242766462;5597.3003374578175;5598.524125449382;5645.774169033192;5037.395512538519;5270.183121616155;5299.966506531189;5305.522502974673;5317.627766056339;2950.5279155335475;2971.524556071018;2976.553516972428;2977.2408035950693;3016.547517872337;5830.4461076197695;5832.133717210452;5839.445252700986;5855.414458554195;5859.414058594191;5890.528757699374;6455.225372955276;6482.282195218271;6555.868882622321;6558.868822623521;6470.385313395314;6493.577917435352;6531.412172904462;6532;6542.8364290893105;6124.724387402666;6335.208098987627;6338.746450142127;6362.586431881913;6362.822877767628;6186.845328866814;6210.4100110489435;6233.501319894488;6244.250689917058;6265;6356.364363563606;6522.249941256816;6546.607203567801;6550.737970481127;6577.408033277028;4337.8264869404875;4413.823447062082;4436.889077772952;4456.353828694886;4459.710118842265;5944.316403613467;6122;6289.056641503811;6296.464800492009;6298.275698294755;6070.757169713339;6078.784243151299;6094.481969032682;6125.84685382869;6159.784407545714;6020.193941817525;6104.8779024420155;6107.786227482195;6133.294671112879;6149.800788846311;5919.940800592111;5934.190072028073;5941.970290148522;6013.699315034319;6064.272287325549;6141.324454310112;6163.537734670007;6170.7223174958135;6223.875522489616;6255.56211065221;5818.970905145447;5894.263217097863;6000;6032.366601506778;6033.396660333931 21:48:52 INFO - PROCESS | 24922 | |5;yui.html;799.320577509117;808.9766445446511;898.384606544517;904.0913881549042;914.8673442350862;15868.603284917883;17475;18151.183196756127;18157.182926768295;18846.43460696181;17171;17387;17517.036562989062;17534.649307013853;17610.295588176457;883.5891310540597;885.9424137431079;888.0852721696631;892.901780804111;894.1773568317163;849.4605925237457;889.2041622747637;892.4377642085477;895.4045559702822;899.3794281945469;931.2177770672668;933.6965486217007;934.6121359635777;936;939.9530023498831;921.0927236671873;926.0693003531475;927.935044546882;928.3779867488798;929.0894922975488;681.8534015186709;683.8392977650249;683.9829004274933;684.6234570985988;684.9006894000343;16455.25951332193;16619.085950272853;16630;16632.667346652997;16644.417445389354;15040;15441;15513.224338783015;15593.37626494939;15757.68484630301;12840.357982100857;13225.338733063309;13353;13435.932820335836;13441.731165376637;4076.5719599441927;4149.128682976579;4157.896052598679;4170.83316667336;4258.978705106455;77803;78001.21998779997;78355.21644783537;79195;79509.60245198794;12389.93805030969;12511.311877846805;12569.811452828215;12581.93709031449;12607.180533265308;22932.656010159862;23011.539769204523;23040.308790736304;23057.423564411023;23094.65358019631;8845.380823342433;8938;9031.27749780016;9203.815923681488;9267.351285409957;5307.7080760557765;5367.872746723154;5427.1316589345515;5434.728263586884;5517;3741.775493470346;3898.2398432305426;3910.335243008695;3915.7454765440157;4139.254934111828;729.5695539631643;738.7229788829189;745.571296504512;747.9326860582573;749.3518106837655;719.7013239505546;732.2055569706928;771.2133623703903;780.5667854340895;789.7867575754628;9957.950210248902;10117.645882394081;10165.898341016644;10215.490801370432;10251.538680759384;660.2572106380322;677.8508728079814;680.119245576979;729.5914287998738;798.2376830127215;4811.4707382187935;4973.751312434364;5230.92153617696;5402.18967154922;5493.670379777226;130289;130699;132815;136663;139273;102294.97705023004;114025;114490.42754786173;117417.41291293659;118686.40656796661;75322.62338688382;79900.20099798928;79914;96138.51930740301;96587.51706241423;10023.548940297558;10542.788650828112;10554.736131596772;10611.734706632396;10699.625513107005;43331.13337733291;48847;49938.750306248236;50218.99562008813;52219.73890130525;71957.28042719506;74929.62535187289;75649.62175189088;75807.62096189483;77699;12883.935580322039;13433.731325373634;13915.025393286263;14100.929495352868;14138.929305353407;15759.606009849846;15937.601559961095;15956.202189890228;15958.8404115962;16021.759673605138;13064.085514013928;13110;13384.330783460982;13859.237741924291;14233.074850134708;14594.85405145935;15019.699606008036;15419.306131224346;15768.290426931046;15843.603909902346;14077.225752583297;14113.858861411665;14163.716725665636;14446.638834029234;14620;12398.81401778956;12472.937635312128;12481.500739970663;12491.87508124907;12555.372231388212;11717.238379505305;11937.641870743892;12022.218555793845;12105.636830895088;12170.14808963364 21:48:52 INFO - PROCESS | 24922 | __end_tp_report 21:48:52 INFO - PROCESS | 24922 | __start_cc_report 21:48:52 INFO - PROCESS | 24922 | _x_x_mozilla_cycle_collect,347 21:48:52 INFO - PROCESS | 24922 | __end_cc_report 21:48:52 INFO - PROCESS | 24922 | __startTimestamp1465274932919__endTimestamp 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | ------- Summary: start ------- 21:48:52 INFO - PROCESS | 24922 | Number of tests: 6 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | [#0] dojo.html Cycles:165 Average:5808.93 Median:6020.00 stddev:662.43 (11.0%) stddev-sans-first:636.90 21:48:52 INFO - PROCESS | 24922 | Values: 3398.3 3444.1 3444.3 3453.3 3459.2 5747.0 5762.3 5811.6 5816.7 5860.1 5795.2 5837.3 5840.9 5887.5 5893.1 5234.8 5282.5 5361.0 5361.4 5362.5 5162.6 5176.5 5368.6 5369.3 5379.7 5295.1 5297.3 5303.2 5320.5 5344.6 5323.0 5331.1 5395.5 5401.5 5408.3 5272.9 5304.8 5308.2 5344.6 5370.5 5582.9 5598.0 5649.9 5661.6 5672.9 6261.3 6278.5 6281.0 6282.9 6284.0 6274.0 6290.8 6293.2 6294.7 6297.1 5712.5 5765.4 5832.5 5842.8 5854.5 6376.6 6415.8 6422.6 6434.7 6468.2 6160.1 6161.0 6189.3 6205.5 6228.2 6020.0 6114.3 6180.4 6239.7 6323.4 6291.7 6300.7 6306.1 6306.5 6325.7 6031.9 6270.9 6280.3 6289.7 6307.2 4717.2 4723.2 4775.0 4792.4 4796.2 4250.9 4274.8 4282.9 4287.7 4297.9 5775.3 5912.0 6005.0 6018.0 6019.8 6069.4 6104.7 6138.6 6192.2 6355.9 5914.9 5916.9 5918.6 5924.1 5926.3 5149.9 5329.3 5338.8 5346.7 5366.4 6427.2 6581.3 6586.8 6591.0 6592.8 6334.6 6340.7 6357.6 6360.0 6373.1 6259.2 6419.3 6463.4 6556.5 6593.2 6233.5 6259.6 6262.0 6269.5 6276.9 6245.3 6276.9 6295.2 6298.4 6303.4 5917.6 6020.3 6062.7 6075.6 6170.7 6062.2 6093.8 6102.0 6105.4 6132.8 5957.3 5965.3 5993.4 6008.5 6014.3 5948.0 6088.9 6110.0 6150.5 6239.7 6083.1 6120.9 6123.0 6125.3 6131.7 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | [#1] ext.html Cycles:180 Average:62721.44 Median:41689.37 stddev:138688.12 (332.7%) stddev-sans-first:138998.73 21:48:52 INFO - PROCESS | 24922 | Values: 596.3 640.8 657.3 670.4 681.6 41647.6 45645.8 45736.8 45798.8 45867.8 46329.5 46729.0 46848.3 46985.3 47017.3 9075.6 9225.8 9253.4 9268.6 9274.7 9048.3 9110.5 9151.5 9154.6 9158.5 9101.9 9187.4 9207.6 9224.3 9227.6 9011.6 9073.7 9143.0 9167.9 9179.2 8970.5 9141.2 9175.1 9177.9 9208.0 42508.1 42828.0 42870.4 42884.1 42884.6 3568.3 3675.1 3820.8 3891.5 4329.3 34245.1 34264.7 34411.3 34504.1 35299.5 4442.4 4443.1 4481.8 4488.9 4546.3 368243.0 368253.0 368379.0 368770.0 370619.0 37612.0 56469.4 56554.7 56896.0 57147.7 37907.4 40340.6 40469.8 40540.4 40834.8 17313.8 17515.4 17548.8 17601.1 17619.9 14429.4 14453.6 14476.3 14553.3 14568.2 9212.0 9216.3 9250.9 9281.0 9347.6 3523.0 3530.3 3560.9 3583.2 3621.1 1037.9 1080.5 1153.1 1159.5 1181.7 32680.7 33238.2 33363.2 33444.8 33600.8 1149.6 1169.9 1221.2 1232.7 1302.2 17344.7 17468.4 17522.1 17623.0 18879.7 587818.0 590189.0 608423.0 916902.0 923452.0 59581.0 60035.4 60317.4 60834.4 60840.0 1481.5 1488.4 1489.4 1517.1 1522.5 31344.7 31830.8 32001.2 32440.2 32598.0 51929.5 52025.7 52590.5 52635.2 52704.7 328280.0 332726.0 332804.0 333113.0 333447.0 28929.4 29176.1 29199.1 29285.3 29286.7 48393.0 48753.0 48928.0 49481.8 49566.5 40850.0 41088.0 41800.4 42025.4 42639.4 40848.0 41628.4 42150.4 42496.4 42809.0 41655.4 41723.4 41941.6 42696.6 42904.6 36838.0 37203.4 37738.0 37766.8 37882.7 28605.3 28857.3 28939.0 29064.3 29091.6 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | [#2] jquery.html Cycles:180 Average:30308.04 Median:11774.99 stddev:86662.93 (736.0%) stddev-sans-first:86876.94 21:48:52 INFO - PROCESS | 24922 | Values: 400.7 405.1 407.2 408.6 416.6 18486.6 20259.1 20508.0 20524.0 21403.1 19860.4 20102.6 20606.6 20688.3 20734.7 5439.6 5710.9 5723.5 5741.7 5768.0 5586.7 5733.1 5744.6 5745.7 5795.7 5706.9 5720.1 5742.4 5747.2 5762.9 5561.5 5686.6 5696.5 5720.7 5763.1 5102.6 5116.8 5157.9 5178.6 5179.8 17795.1 18215.8 18743.5 22207.4 23073.0 2452.3 2500.9 2566.9 2743.7 2853.2 15112.0 15523.5 15548.3 15579.8 15676.5 2040.5 2089.8 2111.1 2146.8 2154.6 81676.6 84501.0 84547.0 84889.2 85427.6 10611.9 10640.7 10936.6 10953.1 11181.3 14708.2 14803.6 14824.0 14857.0 14900.3 8790.3 8997.4 9010.5 9114.9 9187.7 9090.4 9100.2 9228.7 9250.6 9369.0 6917.6 6936.7 6955.9 6970.2 7019.5 1962.3 1989.3 1991.3 2010.8 2023.1 1035.5 1084.6 1143.7 1170.7 1177.2 18921.0 19783.2 20287.2 20379.4 20937.0 1066.8 1089.6 1096.4 1100.7 1157.3 11516.3 11598.9 11761.4 11993.0 11995.8 509901.0 527651.0 531723.0 532590.0 536762.0 84568.6 86846.0 86931.6 87006.1 90587.0 1087.6 1134.2 1153.8 1156.0 1161.3 18270.6 18377.0 18478.8 18758.8 18782.1 32110.0 39057.2 40229.2 41444.6 41764.2 53756.0 54972.5 55861.2 55923.4 56481.7 8671.7 8726.9 8836.9 8885.5 9051.7 13925.0 14055.4 14098.6 14116.9 14504.2 12214.3 12254.9 12351.8 12611.0 12658.2 11665.4 12360.2 12418.5 12561.6 12605.1 12419.5 12497.3 12609.7 12696.1 12816.3 8742.0 8855.9 8885.4 9025.8 9076.3 12138.5 12376.4 12649.7 12823.2 12885.7 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | [#3] mootools.html Cycles:180 Average:6369.92 Median:4561.19 stddev:3642.14 (79.9%) stddev-sans-first:3634.72 21:48:52 INFO - PROCESS | 24922 | Values: 1600.3 1613.9 1774.8 1905.7 1907.3 9115.0 9639.0 9764.2 9785.8 10043.8 9404.1 9607.1 9644.5 9695.1 9708.9 2567.2 2586.2 2604.4 2608.6 2610.5 2566.5 2644.3 2645.7 2648.7 2653.9 2521.8 2553.5 2557.4 2560.0 2561.8 2534.3 2547.9 2554.8 2568.5 2579.7 2466.9 2493.0 2502.7 2576.5 2580.0 9680.4 9753.0 9762.6 9789.2 9803.2 5796.7 5836.3 5891.5 5899.4 5944.7 3631.1 3633.2 3733.4 3788.0 3810.5 1112.6 1133.6 1150.9 1157.8 1171.5 12921.8 13024.8 13039.7 13133.5 13134.2 4838.5 4888.1 5044.1 5045.2 5059.1 10373.5 10377.8 10426.9 10432.6 10449.6 5652.2 5655.1 5683.7 5714.7 5723.9 4584.2 5278.7 5298.3 5346.6 5360.3 4053.8 4109.6 4168.1 4180.5 4187.7 1187.4 1195.8 1203.4 1211.8 1212.1 1089.4 1102.0 1102.7 1104.0 1111.7 6525.6 6641.5 6719.2 6771.4 6806.5 473.8 479.5 482.9 495.2 502.5 4477.9 4508.6 4517.3 4519.2 4538.2 11804.9 12228.1 12263.7 12327.3 12354.9 11648.0 12068.6 12109.1 12257.3 12452.1 9003.2 9098.1 9103.5 9114.1 9119.2 6719.3 6788.3 6896.3 6938.9 7059.0 11141.8 11218.9 11239.2 11303.8 11384.4 11411.7 11442.7 11480.4 11552.5 11577.6 8380.5 8599.1 8689.1 8735.6 8744.0 9444.9 9543.8 9550.2 9646.2 9661.8 8339.0 8644.3 8691.3 8711.7 8728.6 8267.2 8346.4 8408.4 8457.4 8529.1 7506.5 7518.5 7590.5 7598.9 7612.7 5650.7 5841.2 5889.6 5925.8 5942.4 5650.0 5770.6 5834.2 5938.4 5940.0 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | [#4] prototype.html Cycles:180 Average:5048.99 Median:5650.48 stddev:1506.70 (26.7%) stddev-sans-first:1469.16 21:48:52 INFO - PROCESS | 24922 | Values: 355.0 362.4 362.5 370.7 374.5 5655.2 5741.7 5926.8 5930.3 5948.3 5754.0 5756.7 5757.9 5770.9 5776.4 4407.0 4480.0 4481.5 4511.5 4515.3 4360.8 4441.1 4454.8 4461.9 4491.0 4375.8 4390.7 4399.3 4422.4 4468.0 4441.8 4445.1 4462.6 4473.7 4481.1 3876.4 3879.3 3919.6 3957.6 3966.1 5564.4 5609.8 5614.8 5619.8 5641.9 5157.0 5178.0 5178.1 5180.7 5182.6 5968.9 6080.1 6089.2 6133.9 6137.6 4330.8 4335.9 4344.9 4377.1 4378.7 6380.9 6382.8 6391.6 6398.3 6418.8 4177.8 4415.5 4437.4 4448.9 4456.9 4481.5 4484.1 4503.2 4507.3 4507.9 5155.2 5156.2 5161.4 5169.8 5196.3 5847.8 5874.3 6074.1 6121.6 6132.2 1549.0 1550.0 1567.5 1571.2 1573.3 972.2 979.2 988.4 996.7 1007.3 5247.2 5288.8 5297.9 5306.5 5347.4 5566.3 5595.9 5597.3 5598.5 5645.8 5037.4 5270.2 5300.0 5305.5 5317.6 2950.5 2971.5 2976.6 2977.2 3016.5 5830.4 5832.1 5839.4 5855.4 5859.4 5890.5 6455.2 6482.3 6555.9 6558.9 6470.4 6493.6 6531.4 6532.0 6542.8 6124.7 6335.2 6338.7 6362.6 6362.8 6186.8 6210.4 6233.5 6244.3 6265.0 6356.4 6522.2 6546.6 6550.7 6577.4 4337.8 4413.8 4436.9 4456.4 4459.7 5944.3 6122.0 6289.1 6296.5 6298.3 6070.8 6078.8 6094.5 6125.8 6159.8 6020.2 6104.9 6107.8 6133.3 6149.8 5919.9 5934.2 5942.0 6013.7 6064.3 6141.3 6163.5 6170.7 6223.9 6255.6 5819.0 5894.3 6000.0 6032.4 6033.4 21:48:52 INFO - PROCESS | 24922 | 21:48:52 INFO - PROCESS | 24922 | [#5] yui.html Cycles:180 Average:22350.45 Median:3904.29 stddev:32686.35 (837.2%) stddev-sans-first:32737.99 21:48:52 INFO - PROCESS | 24922 | Values: 799.3 809.0 898.4 904.1 914.9 15868.6 17475.0 18151.2 18157.2 18846.4 17171.0 17387.0 17517.0 17534.6 17610.3 883.6 885.9 888.1 892.9 894.2 849.5 889.2 892.4 895.4 899.4 931.2 933.7 934.6 936.0 940.0 921.1 926.1 927.9 928.4 929.1 681.9 683.8 684.0 684.6 684.9 16455.3 16619.1 16630.0 16632.7 16644.4 15040.0 15441.0 15513.2 15593.4 15757.7 12840.4 13225.3 13353.0 13435.9 13441.7 4076.6 4149.1 4157.9 4170.8 4259.0 77803.0 78001.2 78355.2 79195.0 79509.6 12389.9 12511.3 12569.8 12581.9 12607.2 22932.7 23011.5 23040.3 23057.4 23094.7 8845.4 8938.0 9031.3 9203.8 9267.4 5307.7 5367.9 5427.1 5434.7 5517.0 3741.8 3898.2 3910.3 3915.7 4139.3 729.6 738.7 745.6 747.9 749.4 719.7 732.2 771.2 780.6 789.8 9958.0 10117.6 10165.9 10215.5 10251.5 660.3 677.9 680.1 729.6 798.2 4811.5 4973.8 5230.9 5402.2 5493.7 130289.0 130699.0 132815.0 136663.0 139273.0 102295.0 114025.0 114490.4 117417.4 118686.4 75322.6 79900.2 79914.0 96138.5 96587.5 10023.5 10542.8 10554.7 10611.7 10699.6 43331.1 48847.0 49938.8 50219.0 52219.7 71957.3 74929.6 75649.6 75807.6 77699.0 12883.9 13433.7 13915.0 14100.9 14138.9 15759.6 15937.6 15956.2 15958.8 16021.8 13064.1 13110.0 13384.3 13859.2 14233.1 14594.9 15019.7 15419.3 15768.3 15843.6 14077.2 14113.9 14163.7 14446.6 14620.0 12398.8 12472.9 12481.5 12491.9 12555.4 11717.2 11937.6 12022.2 12105.6 12170.1 21:48:52 INFO - PROCESS | 24922 | -------- Summary: end -------- 21:48:52 INFO - PROCESS | 24922 | 21:48:53 INFO - TEST-INFO | 24922: exit 0 21:48:53 INFO - TEST-OK | dromaeo_css | took 1110799ms 21:48:53 INFO - TEST-START | kraken 21:48:53 INFO - operating with platform_type : linux_ 21:48:53 INFO - Initialising browser for kraken test... 21:48:53 INFO - TEST-INFO | started process 25168 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1AuHCH/profile http://localhost:54813/getInfo.html) 21:48:58 INFO - PROCESS | 25168 | __metrics Screen width/height:1600/1200 21:48:58 INFO - PROCESS | 25168 | colorDepth:24 21:48:58 INFO - PROCESS | 25168 | Browser inner width/height: 1024/697 21:48:58 INFO - PROCESS | 25168 | __metrics 21:48:59 ERROR - PROCESS | 25168 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2944: TypeError: this.tabs is undefined 21:48:59 INFO - TEST-INFO | 25168: exit 0 21:48:59 INFO - Browser initialized. 21:48:59 INFO - Running cycle 1/1 for kraken test... 21:48:59 INFO - TEST-INFO | started process 25297 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp1AuHCH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 21:49:11 INFO - PROCESS | 25297 | RSS: Main: 156057600 21:49:11 INFO - PROCESS | 25297 | 21:49:14 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54813/tests/kraken/driver.html?testName=audio-beat-detection) 21:49:15 INFO - PROCESS | 25297 | RSS: Main: 161619968 21:49:15 INFO - PROCESS | 25297 | 21:49:17 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54813/tests/kraken/driver.html?testName=audio-dft) 21:49:18 INFO - PROCESS | 25297 | RSS: Main: 160595968 21:49:18 INFO - PROCESS | 25297 | 21:49:21 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54813/tests/kraken/driver.html?testName=audio-fft) 21:49:21 INFO - PROCESS | 25297 | RSS: Main: 161447936 21:49:21 INFO - PROCESS | 25297 | 21:49:23 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54813/tests/kraken/driver.html?testName=audio-oscillator) 21:49:24 INFO - PROCESS | 25297 | RSS: Main: 161460224 21:49:24 INFO - PROCESS | 25297 | 21:49:26 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54813/tests/kraken/driver.html?testName=imaging-gaussian-blur) 21:49:27 INFO - PROCESS | 25297 | RSS: Main: 161660928 21:49:27 INFO - PROCESS | 25297 | 21:49:30 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54813/tests/kraken/driver.html?testName=imaging-darkroom) 21:49:30 INFO - PROCESS | 25297 | RSS: Main: 160894976 21:49:30 INFO - PROCESS | 25297 | 21:49:34 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54813/tests/kraken/driver.html?testName=imaging-desaturate) 21:49:35 INFO - PROCESS | 25297 | RSS: Main: 161464320 21:49:35 INFO - PROCESS | 25297 | 21:49:37 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54813/tests/kraken/driver.html?testName=json-parse-financial) 21:49:38 INFO - PROCESS | 25297 | RSS: Main: 161058816 21:49:38 INFO - PROCESS | 25297 | 21:49:39 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54813/tests/kraken/driver.html?testName=json-stringify-tinderbox) 21:49:40 INFO - PROCESS | 25297 | RSS: Main: 161021952 21:49:40 INFO - PROCESS | 25297 | 21:49:42 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-aes) 21:49:43 INFO - PROCESS | 25297 | RSS: Main: 161419264 21:49:43 INFO - PROCESS | 25297 | 21:49:45 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-ccm) 21:49:45 INFO - PROCESS | 25297 | RSS: Main: 161775616 21:49:45 INFO - PROCESS | 25297 | 21:49:48 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 21:49:48 INFO - PROCESS | 25297 | RSS: Main: 161968128 21:49:48 INFO - PROCESS | 25297 | 21:49:51 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:49:52 INFO - PROCESS | 25297 | RSS: Main: 161546240 21:49:52 INFO - PROCESS | 25297 | 21:49:53 INFO - PROCESS | 25297 | Cycle 1(1): loaded http://localhost:54813/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54813/tests/kraken/driver.html?testName=ai-astar) 21:49:54 INFO - PROCESS | 25297 | RSS: Main: 162136064 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | __start_tp_report 21:49:54 INFO - PROCESS | 25297 | _x_x_mozilla_page_load 21:49:54 INFO - PROCESS | 25297 | _x_x_mozilla_page_load_details 21:49:54 INFO - PROCESS | 25297 | |i|pagename|runs| 21:49:54 INFO - PROCESS | 25297 | |0;ai-astar;112;108;112;113;113;112;112;111;110;111 21:49:54 INFO - PROCESS | 25297 | |1;audio-beat-detection;149;129;158;177;133;150;157;125;176;139 21:49:54 INFO - PROCESS | 25297 | |2;audio-dft;162;166;163;161;163;165;165;162;163;164 21:49:54 INFO - PROCESS | 25297 | |3;audio-fft;78;75;76;179;75;78;75;74;176;75 21:49:54 INFO - PROCESS | 25297 | |4;audio-oscillator;84;84;154;86;83;84;134;83;84;136 21:49:54 INFO - PROCESS | 25297 | |5;imaging-gaussian-blur;117;118;117;117;117;117;118;117;118;117 21:49:54 INFO - PROCESS | 25297 | |6;imaging-darkroom;146;147;148;148;147;146;148;147;148;146 21:49:54 INFO - PROCESS | 25297 | |7;imaging-desaturate;95;96;96;95;95;95;95;95;95;95 21:49:54 INFO - PROCESS | 25297 | |8;json-parse-financial;51;52;55;52;52;54;54;50;52;53 21:49:54 INFO - PROCESS | 25297 | |9;json-stringify-tinderbox;60;60;60;60;60;60;60;61;60;60 21:49:54 INFO - PROCESS | 25297 | |10;stanford-crypto-aes;75;77;79;76;76;77;79;77;110;77 21:49:54 INFO - PROCESS | 25297 | |11;stanford-crypto-ccm;136;127;127;129;130;118;127;121;123;133 21:49:54 INFO - PROCESS | 25297 | |12;stanford-crypto-pbkdf2;159;154;158;156;155;156;155;159;160;157 21:49:54 INFO - PROCESS | 25297 | |13;stanford-crypto-sha256-iterative;67;65;71;62;63;62;63;62;65;62 21:49:54 INFO - PROCESS | 25297 | __end_tp_report 21:49:54 INFO - PROCESS | 25297 | __start_cc_report 21:49:54 INFO - PROCESS | 25297 | _x_x_mozilla_cycle_collect,899 21:49:54 INFO - PROCESS | 25297 | __end_cc_report 21:49:54 INFO - PROCESS | 25297 | __startTimestamp1465274994107__endTimestamp 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | ------- Summary: start ------- 21:49:54 INFO - PROCESS | 25297 | Number of tests: 14 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:1.51 (1.3%) stddev-sans-first:1.58 21:49:54 INFO - PROCESS | 25297 | Values: 112.0 108.0 112.0 113.0 113.0 112.0 112.0 111.0 110.0 111.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#1] audio-beat-detection Cycles:10 Average:149.30 Median:153.50 stddev:18.23 (11.9%) stddev-sans-first:19.33 21:49:54 INFO - PROCESS | 25297 | Values: 149.0 129.0 158.0 177.0 133.0 150.0 157.0 125.0 176.0 139.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#2] audio-dft Cycles:10 Average:163.40 Median:163.50 stddev:1.58 (1.0%) stddev-sans-first:1.59 21:49:54 INFO - PROCESS | 25297 | Values: 162.0 166.0 163.0 161.0 163.0 165.0 165.0 162.0 163.0 164.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#3] audio-fft Cycles:10 Average:96.10 Median:75.00 stddev:42.93 (57.2%) stddev-sans-first:45.03 21:49:54 INFO - PROCESS | 25297 | Values: 78.0 75.0 76.0 179.0 75.0 78.0 75.0 74.0 176.0 75.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#4] audio-oscillator Cycles:10 Average:101.20 Median:84.00 stddev:28.19 (33.6%) stddev-sans-first:29.20 21:49:54 INFO - PROCESS | 25297 | Values: 84.0 84.0 154.0 86.0 83.0 84.0 134.0 83.0 84.0 136.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#5] imaging-gaussian-blur Cycles:10 Average:117.30 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 21:49:54 INFO - PROCESS | 25297 | Values: 117.0 118.0 117.0 117.0 117.0 117.0 118.0 117.0 118.0 117.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.50 stddev:0.88 (0.6%) stddev-sans-first:0.83 21:49:54 INFO - PROCESS | 25297 | Values: 146.0 147.0 148.0 148.0 147.0 146.0 148.0 147.0 148.0 146.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#7] imaging-desaturate Cycles:10 Average:95.20 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 21:49:54 INFO - PROCESS | 25297 | Values: 95.0 96.0 96.0 95.0 95.0 95.0 95.0 95.0 95.0 95.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#8] json-parse-financial Cycles:10 Average:52.50 Median:52.50 stddev:1.51 (2.9%) stddev-sans-first:1.50 21:49:54 INFO - PROCESS | 25297 | Values: 51.0 52.0 55.0 52.0 52.0 54.0 54.0 50.0 52.0 53.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#9] json-stringify-tinderbox Cycles:10 Average:60.10 Median:60.00 stddev:0.32 (0.5%) stddev-sans-first:0.33 21:49:54 INFO - PROCESS | 25297 | Values: 60.0 60.0 60.0 60.0 60.0 60.0 60.0 61.0 60.0 60.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#10] stanford-crypto-aes Cycles:10 Average:80.30 Median:77.00 stddev:10.51 (13.6%) stddev-sans-first:10.97 21:49:54 INFO - PROCESS | 25297 | Values: 75.0 77.0 79.0 76.0 76.0 77.0 79.0 77.0 110.0 77.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#11] stanford-crypto-ccm Cycles:10 Average:127.10 Median:128.00 stddev:5.40 (4.2%) stddev-sans-first:4.68 21:49:54 INFO - PROCESS | 25297 | Values: 136.0 127.0 127.0 129.0 130.0 118.0 127.0 121.0 123.0 133.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:156.90 Median:157.50 stddev:2.02 (1.3%) stddev-sans-first:2.00 21:49:54 INFO - PROCESS | 25297 | Values: 159.0 154.0 158.0 156.0 155.0 156.0 155.0 159.0 160.0 157.0 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - PROCESS | 25297 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.20 Median:64.00 stddev:2.94 (4.6%) stddev-sans-first:2.93 21:49:54 INFO - PROCESS | 25297 | Values: 67.0 65.0 71.0 62.0 63.0 62.0 63.0 62.0 65.0 62.0 21:49:54 INFO - PROCESS | 25297 | -------- Summary: end -------- 21:49:54 INFO - PROCESS | 25297 | 21:49:54 INFO - TEST-INFO | 25297: exit 0 21:49:55 INFO - TEST-OK | kraken | took 61398ms 21:49:55 INFO - SUITE-END | took 1172s 21:49:55 INFO - Completed test suite (00:19:32) 21:49:55 INFO - javascript benchmark 21:49:55 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7699.432206602914, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3398.2523844754155, 3444.1389652586854, 3444.3111377724463, 3453.274812289419, 3459.1525076356297, 5747.0, 5762.31329526998, 5811.622244554106, 5816.709164541777, 5860.062390017598, 5795.188673585691, 5837.328707198673, 5840.883182336362, 5887.528997680186, 5893.145493903381, 5234.842954711356, 5282.498162674539, 5360.973195134019, 5361.437049109846, 5362.544183744387, 5162.586993040566, 5176.50823171798, 5368.570514358861, 5369.328833895763, 5379.731013449332, 5295.0998330283755, 5297.284866543006, 5303.204519322094, 5320.521153096221, 5344.572434205274, 5323.0152421801895, 5331.147016477365, 5395.541378982791, 5401.540869026137, 5408.2698835657075, 5272.947270527284, 5304.814331498379, 5308.150695888678, 5344.599155063386, 5370.5435038021815, 5582.944170558324, 5598.048331783566, 5649.915251271234, 5661.60368774186, 5672.914906276409, 6261.27995280544, 6278.529110316699, 6280.968595157041, 6282.937170628326, 6283.968580157116, 6274.0, 6290.811275661737, 6293.213348331458, 6294.7482100715915, 6297.118403423525, 5712.542996560265, 5765.394633563457, 5832.533397328161, 5842.766289348463, 5854.4730974212525, 6376.649284289409, 6415.775447859396, 6422.582532135396, 6434.74261029563, 6468.191476065492, 6160.1375807387, 6161.0, 6189.319174890758, 6205.5345849061505, 6228.159198508234, 6020.0, 6114.297368146176, 6180.351063138396, 6239.656818874914, 6323.399277068763, 6291.653959032297, 6300.684965751786, 6306.054091886253, 6306.527010474233, 6325.746970121236, 6031.909521357183, 6270.874582508415, 6280.309165991737, 6289.7484100635465, 6307.180066591313, 4717.22165842639, 4723.244280915037, 4775.0, 4792.424909010941, 4796.160671882407, 4250.914981700349, 4274.807633656493, 4282.935755963663, 4287.721298115613, 4297.935530967038, 5775.335836378787, 5912.024515954819, 6005.039193728982, 6018.0, 6019.849503762441, 6069.393060693984, 6104.694765261719, 6138.570300079041, 6192.195014648067, 6355.872882542323, 5914.940850591525, 5916.940830591725, 5918.556108291896, 5924.052151655801, 5926.318473375531, 5149.922751158736, 5329.25390445338, 5338.813141540028, 5346.679199248058, 5366.409694933554, 6427.164468619049, 6581.2760596334365, 6586.80239592813, 6590.9670451647435, 6592.835179120464, 6334.556581039283, 6340.746370145235, 6357.618542887441, 6360.014197799349, 6373.075903993988, 6259.217597800275, 6419.293877673359, 6463.418292353523, 6556.475481961527, 6593.175853018372, 6233.501319894307, 6259.561830671809, 6262.029385445263, 6269.529785266214, 6276.9372306276355, 6245.313015568193, 6276.937230627818, 6295.150154729236, 6298.401651843067, 6303.401176888188, 5917.556183286185, 6020.307664618625, 6062.7271772769445, 6075.605085669417, 6170.7223174958135, 6062.18160548338, 6093.786717464867, 6101.969490152521, 6105.389461053859, 6132.785352512819, 5957.314908785372, 5965.28416590012, 5993.4006599341465, 6008.489278411209, 6014.278286605635, 5947.9702601486715, 6088.908666370096, 6109.991851344478, 6150.477209437248, 6239.656818875005, 6083.148359229623, 6120.938790612036, 6122.957601858128, 6125.295591007091, 6131.729353333239], "value": 5764.628307276295, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.2606368103553, 640.7885397818719, 657.3131078023468, 670.4033410264864, 681.6182937554967, 41647.58352416475, 45645.77177114118, 45736.77131614346, 45798.77100614496, 45867.77066114665, 46329.53670463294, 46729.0, 46848.29727554089, 46985.295220571716, 47017.29474057892, 9075.591598378089, 9225.769355766126, 9253.444793312423, 9268.629254829799, 9274.721758347227, 9048.321375896801, 9110.544472776368, 9151.496667683276, 9154.588043538024, 9158.542072896362, 9101.863472047924, 9187.35688501805, 9207.585658645343, 9224.262059035294, 9227.630894764201, 9011.594478248462, 9073.727788166365, 9143.0, 9167.862482062774, 9179.17387435134, 8970.461772293618, 9141.177294043502, 9175.082491750838, 9177.908220917772, 9208.0, 42508.14983700309, 42828.0, 42870.35694464586, 42884.1423171538, 42884.57115428868, 3568.286342731464, 3675.0996005978704, 3820.8089595520114, 3891.5135608048995, 4329.307310830252, 34245.14387140317, 34264.657353426395, 34411.31177376463, 34504.137396565035, 35299.4705079424, 4442.400275962736, 4443.142667077033, 4481.843135490275, 4488.910221795546, 4546.272596384596, 368243.0, 368253.0, 368378.9999999973, 368770.0, 370619.0, 37612.0, 56469.43530564724, 56554.7172264136, 56896.0, 57147.71426142926, 37907.43138852919, 40340.59659403427, 40469.797651012144, 40540.39189412161, 40834.79582602068, 17313.82686173122, 17515.38696145654, 17548.82451175472, 17601.11994400275, 17619.91190044067, 14429.422823087169, 14453.566393008226, 14476.276186190858, 14553.345099470338, 14568.198749068692, 9211.953940230256, 9216.262698984065, 9250.90749092514, 9280.95359523198, 9347.626094956126, 3522.982385088058, 3530.3468858261303, 3560.857565697343, 3583.157957879896, 3621.0766254604905, 1037.9221558383151, 1080.4651697409834, 1153.1236260441956, 1159.5245949160972, 1181.6868529839599, 32680.673193268238, 33238.16904577357, 33363.165920852174, 33444.829430969796, 33600.831995839864, 1149.5804031528432, 1169.8771628978834, 1221.248931906871, 1232.6979889927024, 1302.2121616421928, 17344.65310693779, 17468.38860639897, 17522.12389380526, 17623.030733309533, 18879.716804247946, 587818.0, 590189.0, 608423.0, 916902.0, 923452.0, 59581.0, 60035.399646003854, 60317.396826032054, 60834.39165608287, 60840.0, 1481.4740767027765, 1488.4343949299198, 1489.3744627256578, 1517.0594231576492, 1522.5127959052998, 31344.68655313509, 31830.84084579562, 32001.199970000005, 32440.188995275308, 32598.0, 51929.480705192465, 52025.7398713004, 52590.47409526009, 52635.21047184219, 52704.736476317375, 328280.0, 332726.0, 332804.0, 333113.0, 333447.0, 28929.42141157207, 29176.124716258546, 29199.124026279245, 29285.26786830261, 29286.707132928397, 48393.03213935772, 48753.0, 48928.0, 49481.752591238255, 49566.50433495763, 40850.0, 41088.0, 41800.37299440572, 42025.369619456345, 42639.360409594505, 40848.0, 41628.41194142332, 42150.36774448324, 42496.362554561085, 42809.0, 41655.37516937188, 41723.37414938718, 41941.58058419377, 42696.57303426926, 42904.57095429006, 36838.0, 37203.44194837025, 37738.0, 37766.811165944, 37882.741519441195, 28605.28486787847, 28857.278568035967, 28939.0, 29064.273393164494, 29091.563626545194], "value": 19822.06831639354, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.6954714417042, 405.1491867079136, 407.15108997739713, 408.5505943462192, 416.56885123896774, 18486.630267394645, 20259.088341024653, 20508.0, 20524.0, 21403.143874245052, 19860.40418787439, 20102.597948041068, 20606.587868242663, 20688.2759103431, 20734.688979665316, 5439.564834813215, 5710.942890571104, 5723.51350135239, 5741.655500669952, 5768.0, 5586.692731899764, 5733.140028995643, 5744.59787814851, 5745.6552606843725, 5795.652260864341, 5706.942930570683, 5720.056190728525, 5742.368339482654, 5747.166660834177, 5762.884742305151, 5561.5272701820395, 5686.601937864351, 5696.487316141546, 5720.742566584493, 5763.077907534816, 5102.6428150029515, 5116.795328186868, 5157.896842063175, 5178.637495375326, 5179.792808287664, 17795.110244487725, 18215.817841821543, 18743.531411714815, 22207.44481387962, 23073.0, 2452.31335226137, 2500.8999640014376, 2566.8959959407143, 2743.684476285233, 2853.2296280004284, 15112.0, 15523.534293971199, 15548.300326485336, 15579.844201557953, 15676.451324203597, 2040.540878302385, 2089.8014688604558, 2111.0816794694356, 2146.8389870759597, 2154.590627780732, 81676.59161704213, 84501.0, 84547.0, 84889.15110848875, 85427.57286213592, 10611.946940265403, 10640.680779576625, 10936.617218397318, 10953.123750099972, 11181.273217241016, 14708.191049492167, 14803.555893323217, 14824.0, 14857.0, 14900.329485173193, 8790.296776257885, 8997.415168014059, 9010.50442225684, 9114.908850911406, 9187.724368268962, 9090.36367454272, 9100.180983711498, 9228.676996305097, 9250.583723732449, 9369.0, 6917.584944903322, 6936.716707409045, 6955.89566156508, 6970.198427180838, 7019.5086343956455, 1962.2641509433963, 1989.303743689697, 1991.29309095272, 2010.7587089549347, 2023.1401654296865, 1035.502958579886, 1084.5986984815668, 1143.7026373142876, 1170.7073231692077, 1177.1936223686803, 18921.0, 19783.20867165326, 20287.18851245963, 20379.388618341472, 20937.0, 1066.7866426714688, 1089.629525961177, 1096.3531516405199, 1100.7358234023777, 1157.328749325406, 11516.251443656136, 11598.942005290088, 11761.411929403495, 11993.0, 11995.820062699066, 509901.0, 527651.0, 531723.0, 532590.0, 536762.0, 84568.57715711382, 86846.0, 86931.56534217288, 87006.12993870107, 90587.0041857689, 1087.6465148826505, 1134.211722852626, 1153.8153895376868, 1155.9595414160465, 1161.3032180691519, 18270.63458730791, 18377.0, 18478.81521184771, 18758.812411875708, 18782.060896955372, 32110.0, 39057.218855623294, 40229.1954160921, 41444.58555414528, 41764.1647167061, 53756.0, 54972.45027549673, 55861.16208256798, 55923.440765591826, 56481.71759141178, 8671.69649062305, 8726.91273087261, 8836.867446988172, 8885.511296878734, 9051.683191088543, 13925.0, 14055.367508461939, 14098.647533811736, 14116.858831411555, 14504.202268875313, 12214.328211948428, 12254.938725306316, 12351.814722778985, 12611.0, 12658.177218480769, 11665.416729163338, 12360.196587221802, 12418.503259869503, 12561.623151305475, 12605.054620903653, 12419.503219871465, 12497.25016499013, 12609.68475788076, 12696.111272210857, 12816.295103769382, 8741.956290218508, 8855.867161992446, 8885.378023538291, 9025.774355641162, 9076.27389808827, 12138.514459421523, 12376.381180940736, 12649.747005059664, 12823.230606163661, 12885.677858053623], "value": 9754.423529579983, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1600.2798740566743, 1613.9112348820818, 1774.8047714751376, 1905.7236700678393, 1907.2847682119204, 9114.954425227872, 9639.0, 9764.170045546129, 9785.755356116091, 10043.799124017516, 9404.059594040593, 9607.135357817795, 9644.469554174517, 9695.078967498093, 9708.854367184498, 2567.1785028790746, 2586.2370600672753, 2604.4270260542744, 2608.5695860183046, 2610.464854704781, 2566.5251928393213, 2644.2860427684514, 2645.7486538778785, 2648.72188420216, 2653.9071132510367, 2521.798256139514, 2553.48930213957, 2557.424579469623, 2560.0143944581378, 2561.8334808237496, 2534.277730846709, 2547.8598677072755, 2554.8211625186245, 2568.460623269116, 2579.6646435963203, 2466.9136580219606, 2492.9654193509673, 2502.749725027501, 2576.497582971321, 2579.974200258011, 9680.419174849532, 9753.0, 9762.609495620238, 9789.21686265097, 9803.215742740564, 5796.652200867961, 5836.270466191726, 5891.469767720883, 5899.410058994109, 5944.7324870380935, 3631.1466805300997, 3633.218857945554, 3733.4213196954515, 3788.0, 3810.466534685146, 1112.57165991094, 1133.6372360844532, 1150.8734039255676, 1157.7785436364632, 1171.5079666540073, 12921.806172907414, 13024.804627930589, 13039.674008149777, 13133.474661013644, 13134.211947283193, 4838.491958344323, 4888.120138375056, 5044.117279476077, 5045.2432135180015, 5059.139946209153, 10373.481325933673, 10377.792444151075, 10426.947865260625, 10432.634857779942, 10449.634262800766, 5652.208690783294, 5655.066913959233, 5683.687397193194, 5714.714264286769, 5723.856903577361, 4584.174848527297, 5278.683279003195, 5298.337707786527, 5346.625736198429, 5360.303160589098, 4053.777042262645, 4109.589041095866, 4168.083021735213, 4180.498340199196, 4187.7443808020735, 1187.400362816772, 1195.8266051422495, 1203.4283715235367, 1211.7576484703095, 1212.1272683667746, 1089.4389389464432, 1102.0412241349916, 1102.6636875752813, 1103.9834402483968, 1111.74985628233, 6525.575837570543, 6641.468682505388, 6719.227288861745, 6771.356721111487, 6806.455483561403, 473.78916382210275, 479.5132940065836, 482.92031814750874, 495.24475175357867, 502.53264464048783, 4477.865664030019, 4508.571685689854, 4517.34498497716, 4519.163954668397, 4538.205813982539, 11804.88195118055, 12228.144029917821, 12263.693407664701, 12327.321997290055, 12354.876451235552, 11648.0, 12068.637940861789, 12109.091818113677, 12257.325847078318, 12452.128351015523, 9003.189712925869, 9098.135677110664, 9103.544822758968, 9114.088591140833, 9119.179273865384, 6719.294474080151, 6788.321167883172, 6896.27589103137, 6938.895916561155, 7059.047028651272, 11141.777164456504, 11218.887811121784, 11239.157063220455, 11303.773924521627, 11384.430778460877, 11411.714707132389, 11442.656720298404, 11480.425978701198, 11552.537898483966, 11577.594784182513, 8380.455270407405, 8599.14008599135, 8689.13108689126, 8735.56322183901, 8743.956280218557, 9444.905550944402, 9543.809123817624, 9550.235981121356, 9646.228301735984, 9661.806763864824, 8338.958305208434, 8644.308455323433, 8691.348148888985, 8711.69509067205, 8728.607212675317, 8267.214614611843, 8346.374021948257, 8408.369372297224, 8457.40798144124, 8529.104444033288, 7506.474546781673, 7518.511296765692, 7590.506617069873, 7598.886016709865, 7612.733554325572, 5650.717464126859, 5841.2406387171095, 5889.5582831286965, 5925.762969481172, 5942.435468630473, 5650.0, 5770.567207459541, 5834.212381328466, 5938.376470470538, 5940.0], "value": 4987.720173143977, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [354.9751517393782, 362.4092728851972, 362.48708078069524, 370.679362351566, 374.52061361457345, 5655.179998900157, 5741.712914354286, 5926.822195334142, 5930.2883653961535, 5948.286205655317, 5753.971230143843, 5756.654600723959, 5757.877213943283, 5770.9422905770925, 5776.4223577642215, 4407.0, 4479.977600111994, 4481.4846292676275, 4511.548845115479, 4515.300128480075, 4360.825566977317, 4441.067375851075, 4454.797204754715, 4461.88845278869, 4491.034427598066, 4375.7593332366705, 4390.714603550775, 4399.340098985162, 4422.402975598301, 4467.955320446803, 4441.75570343631, 4445.1331990261915, 4462.598366147046, 4473.686841921067, 4481.148581769486, 3876.3604005339084, 3879.301725689375, 3919.6477215360655, 3957.584453632356, 3966.127451960564, 5564.443555644443, 5609.803656872031, 5614.831555053355, 5619.831405057855, 5641.943580564182, 5156.974215128937, 5178.032347707847, 5178.093833579109, 5180.6891586504935, 5182.585393168538, 5968.940310596882, 6080.118382834465, 6089.203684912962, 6133.877322453526, 6137.631742095488, 4330.783460826946, 4335.869923902288, 4344.891377715582, 4377.124575084996, 4378.6934914555995, 6380.904286435707, 6382.840428989266, 6391.616503009788, 6398.328175541595, 6418.839529011765, 4177.811998460016, 4415.470143582791, 4437.42313499243, 4448.933266001012, 4456.9331460028125, 4481.462224533077, 4484.12559550891, 4503.166914120898, 4507.256302710016, 4507.864764057083, 5155.226715992556, 5156.226566015128, 5161.380634323905, 5169.7932082717025, 5196.32447781786, 5847.8245652630485, 5874.32445268791, 6074.086662701235, 6121.632702037892, 6132.233470816148, 1549.0008944231133, 1549.9767503487458, 1567.4592265668325, 1571.1908367190906, 1573.3156077106496, 972.154225823538, 979.1970584121095, 988.4020167798378, 996.6511720897773, 1007.2596641468515, 5247.1866860636665, 5288.83157905257, 5297.867553311198, 5306.495882891119, 5347.379783905858, 5566.304211973504, 5595.922242766462, 5597.3003374578175, 5598.524125449382, 5645.774169033192, 5037.395512538519, 5270.183121616155, 5299.966506531189, 5305.522502974673, 5317.627766056339, 2950.5279155335475, 2971.524556071018, 2976.553516972428, 2977.2408035950693, 3016.547517872337, 5830.4461076197695, 5832.133717210452, 5839.445252700986, 5855.414458554195, 5859.414058594191, 5890.528757699374, 6455.225372955276, 6482.282195218271, 6555.868882622321, 6558.868822623521, 6470.385313395314, 6493.577917435352, 6531.412172904462, 6532.0, 6542.8364290893105, 6124.724387402666, 6335.208098987627, 6338.746450142127, 6362.586431881913, 6362.822877767628, 6186.845328866814, 6210.4100110489435, 6233.501319894488, 6244.250689917058, 6265.0, 6356.364363563606, 6522.249941256816, 6546.607203567801, 6550.737970481127, 6577.408033277028, 4337.8264869404875, 4413.823447062082, 4436.889077772952, 4456.353828694886, 4459.710118842265, 5944.316403613467, 6122.0, 6289.056641503811, 6296.464800492009, 6298.275698294755, 6070.757169713339, 6078.784243151299, 6094.481969032682, 6125.84685382869, 6159.784407545714, 6020.193941817525, 6104.8779024420155, 6107.786227482195, 6133.294671112879, 6149.800788846311, 5919.940800592111, 5934.190072028073, 5941.970290148522, 6013.699315034319, 6064.272287325549, 6141.324454310112, 6163.537734670007, 6170.7223174958135, 6223.875522489616, 6255.56211065221, 5818.970905145447, 5894.263217097863, 6000.0, 6032.366601506778, 6033.396660333931], "value": 4569.591277409958, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.320577509117, 808.9766445446511, 898.384606544517, 904.0913881549042, 914.8673442350862, 15868.603284917883, 17475.0, 18151.183196756127, 18157.182926768295, 18846.43460696181, 17171.0, 17387.0, 17517.036562989062, 17534.649307013853, 17610.295588176457, 883.5891310540597, 885.9424137431079, 888.0852721696631, 892.901780804111, 894.1773568317163, 849.4605925237457, 889.2041622747637, 892.4377642085477, 895.4045559702822, 899.3794281945469, 931.2177770672668, 933.6965486217007, 934.6121359635777, 936.0, 939.9530023498831, 921.0927236671873, 926.0693003531475, 927.935044546882, 928.3779867488798, 929.0894922975488, 681.8534015186709, 683.8392977650249, 683.9829004274933, 684.6234570985988, 684.9006894000343, 16455.25951332193, 16619.085950272853, 16630.0, 16632.667346652997, 16644.417445389354, 15040.0, 15441.0, 15513.224338783015, 15593.37626494939, 15757.68484630301, 12840.357982100857, 13225.338733063309, 13353.0, 13435.932820335836, 13441.731165376637, 4076.5719599441927, 4149.128682976579, 4157.896052598679, 4170.83316667336, 4258.978705106455, 77803.0, 78001.21998779997, 78355.21644783537, 79195.0, 79509.60245198794, 12389.93805030969, 12511.311877846805, 12569.811452828215, 12581.93709031449, 12607.180533265308, 22932.656010159862, 23011.539769204523, 23040.308790736304, 23057.423564411023, 23094.65358019631, 8845.380823342433, 8938.0, 9031.27749780016, 9203.815923681488, 9267.351285409957, 5307.7080760557765, 5367.872746723154, 5427.1316589345515, 5434.728263586884, 5517.0, 3741.775493470346, 3898.2398432305426, 3910.335243008695, 3915.7454765440157, 4139.254934111828, 729.5695539631643, 738.7229788829189, 745.571296504512, 747.9326860582573, 749.3518106837655, 719.7013239505546, 732.2055569706928, 771.2133623703903, 780.5667854340895, 789.7867575754628, 9957.950210248902, 10117.645882394081, 10165.898341016644, 10215.490801370432, 10251.538680759384, 660.2572106380322, 677.8508728079814, 680.119245576979, 729.5914287998738, 798.2376830127215, 4811.4707382187935, 4973.751312434364, 5230.92153617696, 5402.18967154922, 5493.670379777226, 130289.0, 130699.0, 132815.0, 136663.0, 139273.0, 102294.97705023004, 114025.0, 114490.42754786173, 117417.41291293659, 118686.40656796661, 75322.62338688382, 79900.20099798928, 79914.0, 96138.51930740301, 96587.51706241423, 10023.548940297558, 10542.788650828112, 10554.736131596772, 10611.734706632396, 10699.625513107005, 43331.13337733291, 48847.0, 49938.750306248236, 50218.99562008813, 52219.73890130525, 71957.28042719506, 74929.62535187289, 75649.62175189088, 75807.62096189483, 77699.0, 12883.935580322039, 13433.731325373634, 13915.025393286263, 14100.929495352868, 14138.929305353407, 15759.606009849846, 15937.601559961095, 15956.202189890228, 15958.8404115962, 16021.759673605138, 13064.085514013928, 13110.0, 13384.330783460982, 13859.237741924291, 14233.074850134708, 14594.85405145935, 15019.699606008036, 15419.306131224346, 15768.290426931046, 15843.603909902346, 14077.225752583297, 14113.858861411665, 14163.716725665636, 14446.638834029234, 14620.0, 12398.81401778956, 12472.937635312128, 12481.500739970663, 12491.87508124907, 12555.372231388212, 11717.238379505305, 11937.641870743892, 12022.218555793845, 12105.636830895088, 12170.14808963364], "value": 8199.97394099499, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1522.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 108.0, 112.0, 113.0, 113.0, 112.0, 112.0, 111.0, 110.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 129.0, 158.0, 177.0, 133.0, 150.0, 157.0, 125.0, 176.0, 139.0], "value": 149.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 166.0, 163.0, 161.0, 163.0, 165.0, 165.0, 162.0, 163.0, 164.0], "value": 163.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 76.0, 179.0, 75.0, 78.0, 75.0, 74.0, 176.0, 75.0], "value": 96.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 154.0, 86.0, 83.0, 84.0, 134.0, 83.0, 84.0, 136.0], "value": 101.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 118.0, 117.0, 118.0, 117.0], "value": 117.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 148.0, 148.0, 147.0, 146.0, 148.0, 147.0, 148.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0, 95.0], "value": 95.2, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 52.0, 55.0, 52.0, 52.0, 54.0, 54.0, 50.0, 52.0, 53.0], "value": 52.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 60.0, 61.0, 60.0, 60.0], "value": 60.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 79.0, 76.0, 76.0, 77.0, 79.0, 77.0, 110.0, 77.0], "value": 80.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [136.0, 127.0, 127.0, 129.0, 130.0, 118.0, 127.0, 121.0, 123.0, 133.0], "value": 127.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [159.0, 154.0, 158.0, 156.0, 155.0, 156.0, 155.0, 159.0, 160.0, 157.0], "value": 156.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 65.0, 71.0, 62.0, 63.0, 62.0, 63.0, 62.0, 65.0, 62.0], "value": 64.2, "unit": "score"}]}]} 21:49:55 INFO - Return code: 0 21:49:55 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:49:55 ERROR - # TBPL SUCCESS # 21:49:55 INFO - Running post-action listener: _resource_record_post_action 21:49:55 INFO - ##### 21:49:55 INFO - ##### Finished run-tests step (success) 21:49:55 INFO - ##### 21:49:55 INFO - Running post-run listener: _resource_record_post_run 21:49:55 INFO - Total resource usage - Wall time: 1189s; CPU: 13.0%; Read bytes: 4096; Write bytes: 513036288; Read time: 16; Write time: 489380 21:49:55 INFO - TinderboxPrint: CPU usage
13.4% 21:49:55 INFO - TinderboxPrint: I/O read bytes / time
4,096 / 16 21:49:55 INFO - TinderboxPrint: I/O write bytes / time
513,036,288 / 489,380 21:49:55 INFO - TinderboxPrint: CPU idle
8,213.4 (86.6%) 21:49:55 INFO - TinderboxPrint: CPU user
1,214.0 (12.8%) 21:49:55 INFO - TinderboxPrint: Swap in / out
0 / 0 21:49:55 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 208449536; Read time: 0; Write time: 317688 21:49:55 INFO - run-tests - Wall time: 1174s; CPU: 13.0%; Read bytes: 4096; Write bytes: 247320576; Read time: 16; Write time: 145848 21:49:56 INFO - Running post-run listener: _upload_blobber_files 21:49:56 INFO - Blob upload gear active. 21:49:56 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:49:56 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 21:49:56 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:49:56 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:49:56 INFO - (blobuploader) - INFO - Open directory for files ... 21:49:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 21:49:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:49:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 21:49:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:49:57 INFO - (blobuploader) - INFO - Done attempting. 21:49:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:49:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:49:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:01 INFO - (blobuploader) - INFO - Done attempting. 21:50:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 21:50:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:50:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:50:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 21:50:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:50:01 INFO - (blobuploader) - INFO - Done attempting. 21:50:01 INFO - (blobuploader) - INFO - Iteration through files over. 21:50:01 INFO - Return code: 0 21:50:01 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:50:01 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:50:01 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af85c37d2c16286065ed86d15074b3ce08b69527b1260d98c90ecdb63ef91dd65d78729df27c79af40a784976ca2b10b5cbfa967177bf40ea912124842c33af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22afbadc95c8aa54213291d23d064b7df79f92c21ba2cd3861dbf915728c4c47007fa01aadd38f8dfda57e292d972e7fd264a428a73e4835dd81389d58eb0b0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ff906060f66e03960d9af9fed1a0a49b0c0bd015a995b87d456109d584259fc1c2c2e33b4537f12a125d13efc60e41f76ddfb9e71af36bfc94e044661a46455"} 21:50:01 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:50:01 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:50:01 INFO - Contents: 21:50:01 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af85c37d2c16286065ed86d15074b3ce08b69527b1260d98c90ecdb63ef91dd65d78729df27c79af40a784976ca2b10b5cbfa967177bf40ea912124842c33af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22afbadc95c8aa54213291d23d064b7df79f92c21ba2cd3861dbf915728c4c47007fa01aadd38f8dfda57e292d972e7fd264a428a73e4835dd81389d58eb0b0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ff906060f66e03960d9af9fed1a0a49b0c0bd015a995b87d456109d584259fc1c2c2e33b4537f12a125d13efc60e41f76ddfb9e71af36bfc94e044661a46455"} 21:50:01 INFO - Running post-run listener: copy_logs_to_upload_dir 21:50:01 INFO - Copying logs to upload dir... 21:50:01 INFO - mkdir: /builds/slave/test/build/upload/logs 21:50:01 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1234.991638 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 35 secs) (at 2016-06-06 21:50:01.649371) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 21:50:01.655165) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af85c37d2c16286065ed86d15074b3ce08b69527b1260d98c90ecdb63ef91dd65d78729df27c79af40a784976ca2b10b5cbfa967177bf40ea912124842c33af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22afbadc95c8aa54213291d23d064b7df79f92c21ba2cd3861dbf915728c4c47007fa01aadd38f8dfda57e292d972e7fd264a428a73e4835dd81389d58eb0b0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ff906060f66e03960d9af9fed1a0a49b0c0bd015a995b87d456109d584259fc1c2c2e33b4537f12a125d13efc60e41f76ddfb9e71af36bfc94e044661a46455"} build_url:https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011279 build_url: 'https://queue.taskcluster.net/v1/task/ZPKLA7OCQcKKyFHh0q78oA/artifacts/public/build/firefox-49.0a2.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/af85c37d2c16286065ed86d15074b3ce08b69527b1260d98c90ecdb63ef91dd65d78729df27c79af40a784976ca2b10b5cbfa967177bf40ea912124842c33af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/22afbadc95c8aa54213291d23d064b7df79f92c21ba2cd3861dbf915728c4c47007fa01aadd38f8dfda57e292d972e7fd264a428a73e4835dd81389d58eb0b0b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ff906060f66e03960d9af9fed1a0a49b0c0bd015a995b87d456109d584259fc1c2c2e33b4537f12a125d13efc60e41f76ddfb9e71af36bfc94e044661a46455"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-06 21:50:01.680844) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:50:01.681170) ========= 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=9c43b49b387003dc8f5b4e5c000001c4-1465273654.296971-859113153 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004496 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-06 21:50:01.763449) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 21:50:01.763770) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-06 21:50:01.764056) ========= ========= Total master_lag: 0.18 =========