builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-057 starttime: 1465318564.53 results: success (0) buildid: 20160607070918 builduid: 3dedf474d03f422cb049d1195ace538d revision: 448365b11e377ebf1c572d54e45bbd36c45af03a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.527204) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.527776) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.528114) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.543547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.543879) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-06-07 09:56:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 48.1M=0s 2016-06-07 09:56:05 (48.1 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.096502 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.654526) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.654911) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.021956 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:04.704431) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 09:56:04.704890) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 448365b11e377ebf1c572d54e45bbd36c45af03a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev 448365b11e377ebf1c572d54e45bbd36c45af03a --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=934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-06-07 09:56:05,488 truncating revision to first 12 chars 2016-06-07 09:56:05,489 Setting DEBUG logging. 2016-06-07 09:56:05,489 attempt 1/10 2016-06-07 09:56:05,489 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/448365b11e37?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-06-07 09:56:06,174 unpacking tar archive at: mozilla-inbound-448365b11e37/testing/mozharness/ program finished with exit code 0 elapsedTime=1.032067 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-06-07 09:56:05.755095) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:05.755428) ========= script_repo_revision: 448365b11e377ebf1c572d54e45bbd36c45af03a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:05.755877) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:05.756200) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-06-07 09:56:05.767730) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-07 09:56:05.768324) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] 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=934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 09:56:06 INFO - MultiFileLogger online at 20160607 09:56:06 in /builds/slave/test 09:56:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 09:56:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:56:06 INFO - {'append_to_log': False, 09:56:06 INFO - 'base_work_dir': '/builds/slave/test', 09:56:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 09:56:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:56:06 INFO - 'branch': 'Mozilla-Inbound', 09:56:06 INFO - 'buildbot_json_path': 'buildprops.json', 09:56:06 INFO - 'config_files': ('talos/linux_config.py',), 09:56:06 INFO - 'default_actions': ('clobber', 09:56:06 INFO - 'read-buildbot-config', 09:56:06 INFO - 'download-and-extract', 09:56:06 INFO - 'populate-webroot', 09:56:06 INFO - 'create-virtualenv', 09:56:06 INFO - 'install', 09:56:06 INFO - 'run-tests'), 09:56:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:56:06 INFO - 'download_minidump_stackwalk': True, 09:56:06 INFO - 'download_symbols': 'ondemand', 09:56:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:56:06 INFO - 'tooltool.py': '/tools/tooltool.py', 09:56:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:56:06 INFO - '/tools/misc-python/virtualenv.py')}, 09:56:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:56:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:56:06 INFO - 'installer_path': 'installer.exe', 09:56:06 INFO - 'log_level': 'info', 09:56:06 INFO - 'log_name': 'talos', 09:56:06 INFO - 'log_to_console': True, 09:56:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 09:56:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 09:56:06 INFO - 'opt_config_files': (), 09:56:06 INFO - 'pip_index': False, 09:56:06 INFO - 'sps_profile': False, 09:56:06 INFO - 'sps_profile_interval': 0, 09:56:06 INFO - 'suite': 'dromaeojs', 09:56:06 INFO - 'system_bits': '32', 09:56:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:56:06 INFO - 'title': 'talos-linux64-ix-057', 09:56:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:56:06 INFO - 'use_talos_json': True, 09:56:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:56:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:56:06 INFO - 'work_dir': 'build'} 09:56:06 INFO - ##### 09:56:06 INFO - ##### Running clobber step. 09:56:06 INFO - ##### 09:56:06 INFO - Running pre-action listener: _resource_record_pre_action 09:56:06 INFO - Running main action method: clobber 09:56:06 INFO - rmtree: /builds/slave/test/build 09:56:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:56:07 INFO - Running post-action listener: _resource_record_post_action 09:56:07 INFO - ##### 09:56:07 INFO - ##### Finished clobber step (success) 09:56:07 INFO - ##### 09:56:07 INFO - ##### 09:56:07 INFO - ##### Running read-buildbot-config step. 09:56:07 INFO - ##### 09:56:07 INFO - Running pre-action listener: _resource_record_pre_action 09:56:07 INFO - Running main action method: read_buildbot_config 09:56:07 INFO - Using buildbot properties: 09:56:07 INFO - { 09:56:07 INFO - "project": "", 09:56:07 INFO - "product": "firefox", 09:56:07 INFO - "script_repo_revision": "production", 09:56:07 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 09:56:07 INFO - "repository": "", 09:56:07 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 09:56:07 INFO - "buildid": "20160607070918", 09:56:07 INFO - "pgo_build": "True", 09:56:07 INFO - "basedir": "/builds/slave/test", 09:56:07 INFO - "buildnumber": 1144, 09:56:07 INFO - "slavename": "talos-linux64-ix-057", 09:56:07 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 09:56:07 INFO - "platform": "ubuntu64_hw", 09:56:07 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 09:56:07 INFO - "branch": "mozilla-inbound", 09:56:07 INFO - "repo_path": "integration/mozilla-inbound", 09:56:07 INFO - "revision": "448365b11e377ebf1c572d54e45bbd36c45af03a", 09:56:07 INFO - "stage_platform": "linux64-pgo", 09:56:07 INFO - "builduid": "3dedf474d03f422cb049d1195ace538d", 09:56:07 INFO - "slavebuilddir": "test" 09:56:07 INFO - } 09:56:07 INFO - Found installer url https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2. 09:56:07 INFO - Running post-action listener: _resource_record_post_action 09:56:07 INFO - ##### 09:56:07 INFO - ##### Finished read-buildbot-config step (success) 09:56:07 INFO - ##### 09:56:07 INFO - ##### 09:56:07 INFO - ##### Running download-and-extract step. 09:56:07 INFO - ##### 09:56:07 INFO - Running pre-action listener: _resource_record_pre_action 09:56:07 INFO - Running main action method: download_and_extract 09:56:07 INFO - mkdir: /builds/slave/test/build/tests 09:56:07 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')]} 09:56:07 INFO - https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 09:56:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:56:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:56:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:56:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 09:56:08 INFO - Downloaded 1361 bytes. 09:56:08 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.test_packages.json 09:56:08 INFO - Using the following test package requirements: 09:56:08 INFO - {u'common': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:56:08 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 09:56:08 INFO - u'gtest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.gtest.tests.zip'], 09:56:08 INFO - u'jittest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'jsshell-linux-x86_64.zip'], 09:56:08 INFO - u'mochitest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 09:56:08 INFO - u'mozbase': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'], 09:56:08 INFO - u'reftest': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.reftest.tests.zip'], 09:56:08 INFO - u'talos': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'], 09:56:08 INFO - u'web-platform': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 09:56:08 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 09:56:08 INFO - u'firefox-50.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 09:56:08 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 09:56:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:08 INFO - https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:56:08 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:56:08 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:56:08 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:56:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:56:09 INFO - Downloaded 24440614 bytes. 09:56:09 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:56:09 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:56:10 INFO - Return code: 0 09:56:10 INFO - Downloading packages: [u'firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 09:56:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:10 INFO - https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 09:56:10 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:56:10 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:56:10 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip 09:56:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 09:56:17 INFO - Downloaded 24440614 bytes. 09:56:17 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:56:17 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 09:56:18 INFO - Return code: 0 09:56:18 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')]} 09:56:18 INFO - https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 09:56:18 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:56:18 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:56:18 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip 09:56:18 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 09:56:19 INFO - Downloaded 10833369 bytes. 09:56:19 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 09:56:19 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 09:56:19 INFO - Return code: 0 09:56:19 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')]} 09:56:19 INFO - https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 09:56:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:56:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:56:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:56:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 09:56:21 INFO - Downloaded 59177516 bytes. 09:56:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:56:21 INFO - mkdir: /builds/slave/test/properties 09:56:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:56:21 INFO - Writing to file /builds/slave/test/properties/build_url 09:56:21 INFO - Contents: 09:56:21 INFO - build_url:https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 09:56:22 INFO - Running post-action listener: _resource_record_post_action 09:56:22 INFO - Running post-action listener: set_extra_try_arguments 09:56:22 INFO - ##### 09:56:22 INFO - ##### Finished download-and-extract step (success) 09:56:22 INFO - ##### 09:56:22 INFO - ##### 09:56:22 INFO - ##### Running populate-webroot step. 09:56:22 INFO - ##### 09:56:22 INFO - Running pre-action listener: _resource_record_pre_action 09:56:22 INFO - Running main action method: populate_webroot 09:56:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:56:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:56:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:56:22 INFO - '--tppagecycles', 09:56:22 INFO - '7'], 09:56:22 INFO - 'tests': ['tsvgm']}}, 09:56:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:56:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:56:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:56:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:56:22 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:22 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:56:22 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['damp', 'tps']}, 09:56:22 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'tests': ['damp', 'tps']}, 09:56:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['dromaeo_dom']}, 09:56:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:56:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['a11yr', 09:56:22 INFO - 'ts_paint', 09:56:22 INFO - 'tpaint', 09:56:22 INFO - 'sessionrestore', 09:56:22 INFO - 'sessionrestore_no_auto_restore', 09:56:22 INFO - 'tabpaint']}, 09:56:22 INFO - 'other-e10s': {'tests': ['a11yr', 09:56:22 INFO - 'ts_paint', 09:56:22 INFO - 'tpaint', 09:56:22 INFO - 'sessionrestore', 09:56:22 INFO - 'sessionrestore_no_auto_restore', 09:56:22 INFO - 'tabpaint']}, 09:56:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:56:22 INFO - 'ts_paint', 09:56:22 INFO - 'tpaint', 09:56:22 INFO - 'sessionrestore', 09:56:22 INFO - 'sessionrestore_no_auto_restore', 09:56:22 INFO - 'tabpaint']}, 09:56:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:56:22 INFO - 'ts_paint', 09:56:22 INFO - 'tpaint', 09:56:22 INFO - 'sessionrestore', 09:56:22 INFO - 'sessionrestore_no_auto_restore', 09:56:22 INFO - 'tabpaint']}, 09:56:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['a11yr', 09:56:22 INFO - 'ts_paint', 09:56:22 INFO - 'tpaint', 09:56:22 INFO - 'sessionrestore', 09:56:22 INFO - 'sessionrestore_no_auto_restore', 09:56:22 INFO - 'tabpaint']}, 09:56:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['a11yr', 09:56:22 INFO - 'ts_paint', 09:56:22 INFO - 'tpaint', 09:56:22 INFO - 'sessionrestore', 09:56:22 INFO - 'sessionrestore_no_auto_restore', 09:56:22 INFO - 'tabpaint']}, 09:56:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['tsvgx', 09:56:22 INFO - 'tsvgr_opacity', 09:56:22 INFO - 'tart', 09:56:22 INFO - 'tscrollx', 09:56:22 INFO - 'cart']}, 09:56:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:56:22 INFO - 'tsvgr_opacity', 09:56:22 INFO - 'tart', 09:56:22 INFO - 'tscrollx', 09:56:22 INFO - 'cart']}, 09:56:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'talos_options': ['--disable-e10s'], 09:56:22 INFO - 'tests': ['tp5o']}, 09:56:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'tests': ['tp5o']}, 09:56:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'talos_options': ['--disable-e10s', 09:56:22 INFO - '--xperf_path', 09:56:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:22 INFO - 'tests': ['tp5n']}, 09:56:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:56:22 INFO - 'talos_options': ['--xperf_path', 09:56:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:56:22 INFO - 'tests': ['tp5n']}}, 09:56:22 INFO - 'talos.zip': {'path': '', 09:56:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:56:22 INFO - Running post-action listener: _resource_record_post_action 09:56:22 INFO - ##### 09:56:22 INFO - ##### Finished populate-webroot step (success) 09:56:22 INFO - ##### 09:56:22 INFO - ##### 09:56:22 INFO - ##### Running create-virtualenv step. 09:56:22 INFO - ##### 09:56:22 INFO - Running pre-action listener: _resource_record_pre_action 09:56:22 INFO - Running main action method: create_virtualenv 09:56:22 INFO - Creating virtualenv /builds/slave/test/build/venv 09:56:22 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 09:56:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:56:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:56:22 INFO - Using real prefix '/usr' 09:56:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:56:23 INFO - Installing distribute.............................................................................................................................................................................................done. 09:56:25 INFO - Installing pip.................done. 09:56:25 INFO - Return code: 0 09:56:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:25 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 09:56:25 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 09:56:25 INFO - Using env: {'DISPLAY': ':0', 09:56:25 INFO - 'HOME': '/home/cltbld', 09:56:25 INFO - 'LANG': 'en_US.UTF-8', 09:56:25 INFO - 'LANGUAGE': 'en_US:en', 09:56:25 INFO - 'LOGNAME': 'cltbld', 09:56:25 INFO - 'MAIL': '/var/mail/cltbld', 09:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:25 INFO - 'MOZ_NO_REMOTE': '1', 09:56:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:25 INFO - 'NO_EM_RESTART': '1', 09:56:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:25 INFO - 'PWD': '/builds/slave/test', 09:56:25 INFO - 'SHELL': '/bin/bash', 09:56:25 INFO - 'SHLVL': '1', 09:56:25 INFO - 'TERM': 'linux', 09:56:25 INFO - 'TMOUT': '86400', 09:56:25 INFO - 'USER': 'cltbld', 09:56:25 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:25 INFO - '_': '/tools/buildbot/bin/python'} 09:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:25 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 09:56:25 INFO - Cleaning up... 09:56:25 INFO - Return code: 0 09:56:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:25 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:25 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 09:56:25 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 09:56:25 INFO - Using env: {'DISPLAY': ':0', 09:56:25 INFO - 'HOME': '/home/cltbld', 09:56:25 INFO - 'LANG': 'en_US.UTF-8', 09:56:25 INFO - 'LANGUAGE': 'en_US:en', 09:56:25 INFO - 'LOGNAME': 'cltbld', 09:56:25 INFO - 'MAIL': '/var/mail/cltbld', 09:56:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:25 INFO - 'MOZ_NO_REMOTE': '1', 09:56:25 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:25 INFO - 'NO_EM_RESTART': '1', 09:56:25 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:25 INFO - 'PWD': '/builds/slave/test', 09:56:25 INFO - 'SHELL': '/bin/bash', 09:56:25 INFO - 'SHLVL': '1', 09:56:25 INFO - 'TERM': 'linux', 09:56:25 INFO - 'TMOUT': '86400', 09:56:25 INFO - 'USER': 'cltbld', 09:56:25 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:25 INFO - '_': '/tools/buildbot/bin/python'} 09:56:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:25 INFO - Downloading/unpacking psutil>=3.1.1 09:56:25 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 09:56:25 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 09:56:25 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 09:56:25 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 09:56:25 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 09:56:25 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 09:56:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:56:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:27 INFO - Installing collected packages: psutil 09:56:27 INFO - Running setup.py install for psutil 09:56:27 INFO - building 'psutil._psutil_linux' extension 09:56:27 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 09:56:27 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 09:56:28 INFO - building 'psutil._psutil_posix' extension 09:56:28 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 09:56:28 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 09:56:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:56:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:56:28 INFO - Successfully installed psutil 09:56:28 INFO - Cleaning up... 09:56:28 INFO - Return code: 0 09:56:28 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:28 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:28 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 09:56:28 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 09:56:28 INFO - Using env: {'DISPLAY': ':0', 09:56:28 INFO - 'HOME': '/home/cltbld', 09:56:28 INFO - 'LANG': 'en_US.UTF-8', 09:56:28 INFO - 'LANGUAGE': 'en_US:en', 09:56:28 INFO - 'LOGNAME': 'cltbld', 09:56:28 INFO - 'MAIL': '/var/mail/cltbld', 09:56:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:28 INFO - 'MOZ_NO_REMOTE': '1', 09:56:28 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:28 INFO - 'NO_EM_RESTART': '1', 09:56:28 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:28 INFO - 'PWD': '/builds/slave/test', 09:56:28 INFO - 'SHELL': '/bin/bash', 09:56:28 INFO - 'SHLVL': '1', 09:56:28 INFO - 'TERM': 'linux', 09:56:28 INFO - 'TMOUT': '86400', 09:56:28 INFO - 'USER': 'cltbld', 09:56:28 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:28 INFO - '_': '/tools/buildbot/bin/python'} 09:56:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:28 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:56:28 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 09:56:28 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 09:56:28 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 09:56:28 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 09:56:28 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 09:56:28 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 09:56:30 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:56:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:56:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:56:30 INFO - Installing collected packages: mozsystemmonitor 09:56:30 INFO - Running setup.py install for mozsystemmonitor 09:56:30 INFO - Successfully installed mozsystemmonitor 09:56:30 INFO - Cleaning up... 09:56:30 INFO - Return code: 0 09:56:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:56: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')]} 09:56:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56: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')]} 09:56:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:30 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:30 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 09:56:30 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 09:56:30 INFO - Using env: {'DISPLAY': ':0', 09:56:30 INFO - 'HOME': '/home/cltbld', 09:56:30 INFO - 'LANG': 'en_US.UTF-8', 09:56:30 INFO - 'LANGUAGE': 'en_US:en', 09:56:30 INFO - 'LOGNAME': 'cltbld', 09:56:30 INFO - 'MAIL': '/var/mail/cltbld', 09:56:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:30 INFO - 'MOZ_NO_REMOTE': '1', 09:56:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:30 INFO - 'NO_EM_RESTART': '1', 09:56:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:30 INFO - 'PWD': '/builds/slave/test', 09:56:30 INFO - 'SHELL': '/bin/bash', 09:56:30 INFO - 'SHLVL': '1', 09:56:30 INFO - 'TERM': 'linux', 09:56:30 INFO - 'TMOUT': '86400', 09:56:30 INFO - 'USER': 'cltbld', 09:56:30 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:30 INFO - '_': '/tools/buildbot/bin/python'} 09:56:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:30 INFO - Downloading/unpacking jsonschema==2.5.1 09:56:30 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 09:56:30 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 09:56:30 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 09:56:30 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 09:56:30 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 09:56:30 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 09:56:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:56:32 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:56:32 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 09:56:32 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 09:56:32 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 09:56:32 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 09:56:32 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 09:56:32 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 09:56:32 INFO - Downloading functools32-3.2.3-2.tar.gz 09:56:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:56:33 INFO - warning: no files found matching '*.txt' 09:56:33 INFO - no previously-included directories found matching 'build' 09:56:33 INFO - no previously-included directories found matching 'dist' 09:56:33 INFO - no previously-included directories found matching '.git*' 09:56:33 INFO - Installing collected packages: jsonschema, functools32 09:56:33 INFO - Running setup.py install for functools32 09:56:33 INFO - warning: no files found matching '*.txt' 09:56:33 INFO - no previously-included directories found matching 'build' 09:56:33 INFO - no previously-included directories found matching 'dist' 09:56:33 INFO - no previously-included directories found matching '.git*' 09:56:33 INFO - Successfully installed jsonschema functools32 09:56:33 INFO - Cleaning up... 09:56:33 INFO - Return code: 0 09:56:33 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:56:33 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')]} 09:56:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:33 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')]} 09:56:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:33 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:33 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 09:56:33 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 09:56:33 INFO - Using env: {'DISPLAY': ':0', 09:56:33 INFO - 'HOME': '/home/cltbld', 09:56:33 INFO - 'LANG': 'en_US.UTF-8', 09:56:33 INFO - 'LANGUAGE': 'en_US:en', 09:56:33 INFO - 'LOGNAME': 'cltbld', 09:56:33 INFO - 'MAIL': '/var/mail/cltbld', 09:56:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:33 INFO - 'MOZ_NO_REMOTE': '1', 09:56:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:33 INFO - 'NO_EM_RESTART': '1', 09:56:33 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:33 INFO - 'PWD': '/builds/slave/test', 09:56:33 INFO - 'SHELL': '/bin/bash', 09:56:33 INFO - 'SHLVL': '1', 09:56:33 INFO - 'TERM': 'linux', 09:56:33 INFO - 'TMOUT': '86400', 09:56:33 INFO - 'USER': 'cltbld', 09:56:33 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:33 INFO - '_': '/tools/buildbot/bin/python'} 09:56:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:33 INFO - Downloading/unpacking blobuploader==1.2.4 09:56:33 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 09:56:33 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 09:56:33 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 09:56:33 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 09:56:33 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 09:56:33 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 09:56:35 INFO - Downloading blobuploader-1.2.4.tar.gz 09:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:56:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:56:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:35 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 09:56:36 INFO - Downloading docopt-0.6.1.tar.gz 09:56:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:56:36 INFO - Installing collected packages: blobuploader, requests, docopt 09:56:36 INFO - Running setup.py install for blobuploader 09:56:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:56:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:56:36 INFO - Running setup.py install for requests 09:56:36 INFO - Running setup.py install for docopt 09:56:36 INFO - Successfully installed blobuploader requests docopt 09:56:36 INFO - Cleaning up... 09:56:36 INFO - Return code: 0 09:56:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:36 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:36 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 09:56:36 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 09:56:36 INFO - Using env: {'DISPLAY': ':0', 09:56:36 INFO - 'HOME': '/home/cltbld', 09:56:36 INFO - 'LANG': 'en_US.UTF-8', 09:56:36 INFO - 'LANGUAGE': 'en_US:en', 09:56:36 INFO - 'LOGNAME': 'cltbld', 09:56:36 INFO - 'MAIL': '/var/mail/cltbld', 09:56:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:36 INFO - 'MOZ_NO_REMOTE': '1', 09:56:36 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:36 INFO - 'NO_EM_RESTART': '1', 09:56:36 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:36 INFO - 'PWD': '/builds/slave/test', 09:56:36 INFO - 'SHELL': '/bin/bash', 09:56:36 INFO - 'SHLVL': '1', 09:56:36 INFO - 'TERM': 'linux', 09:56:36 INFO - 'TMOUT': '86400', 09:56:36 INFO - 'USER': 'cltbld', 09:56:36 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:36 INFO - '_': '/tools/buildbot/bin/python'} 09:56:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:37 INFO - Running setup.py (path:/tmp/pip-UHzRPR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:37 INFO - Running setup.py (path:/tmp/pip-GzyBpo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:37 INFO - Running setup.py (path:/tmp/pip-QLnRyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:37 INFO - Running setup.py (path:/tmp/pip-tnifRf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:37 INFO - Running setup.py (path:/tmp/pip-cPoj5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:37 INFO - Running setup.py (path:/tmp/pip-IQ3EwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:37 INFO - Running setup.py (path:/tmp/pip-6blMdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:37 INFO - Running setup.py (path:/tmp/pip-M2ddaE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:37 INFO - Running setup.py (path:/tmp/pip-ITO8Of-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:37 INFO - Running setup.py (path:/tmp/pip-MRH73f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:37 INFO - Running setup.py (path:/tmp/pip-X2ltxz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:37 INFO - Running setup.py (path:/tmp/pip-arUrLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:37 INFO - Running setup.py (path:/tmp/pip-faSwuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:38 INFO - Running setup.py (path:/tmp/pip-6zXhjZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:38 INFO - Running setup.py (path:/tmp/pip-uVItVj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:38 INFO - Running setup.py (path:/tmp/pip-uge2x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:38 INFO - Running setup.py (path:/tmp/pip-ylWpOx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:38 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:56:38 INFO - Running setup.py install for manifestparser 09:56:38 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:56:38 INFO - Running setup.py install for mozcrash 09:56:38 INFO - Running setup.py install for mozdebug 09:56:38 INFO - Running setup.py install for mozdevice 09:56:38 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:56:38 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:56:38 INFO - Running setup.py install for mozfile 09:56:38 INFO - Running setup.py install for mozhttpd 09:56:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:56:38 INFO - Running setup.py install for mozinfo 09:56:39 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Running setup.py install for mozInstall 09:56:39 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Running setup.py install for mozleak 09:56:39 INFO - Running setup.py install for mozlog 09:56:39 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Running setup.py install for moznetwork 09:56:39 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Running setup.py install for mozprocess 09:56:39 INFO - Running setup.py install for mozprofile 09:56:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Running setup.py install for mozrunner 09:56:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:56:39 INFO - Running setup.py install for mozscreenshot 09:56:39 INFO - Running setup.py install for moztest 09:56:40 INFO - Running setup.py install for mozversion 09:56:40 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:56:40 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:56:40 INFO - Cleaning up... 09:56:40 INFO - Return code: 0 09:56:40 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:56:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:40 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:40 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 09:56:40 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 09:56:40 INFO - Using env: {'DISPLAY': ':0', 09:56:40 INFO - 'HOME': '/home/cltbld', 09:56:40 INFO - 'LANG': 'en_US.UTF-8', 09:56:40 INFO - 'LANGUAGE': 'en_US:en', 09:56:40 INFO - 'LOGNAME': 'cltbld', 09:56:40 INFO - 'MAIL': '/var/mail/cltbld', 09:56:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:40 INFO - 'MOZ_NO_REMOTE': '1', 09:56:40 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:40 INFO - 'NO_EM_RESTART': '1', 09:56:40 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:40 INFO - 'PWD': '/builds/slave/test', 09:56:40 INFO - 'SHELL': '/bin/bash', 09:56:40 INFO - 'SHLVL': '1', 09:56:40 INFO - 'TERM': 'linux', 09:56:40 INFO - 'TMOUT': '86400', 09:56:40 INFO - 'USER': 'cltbld', 09:56:40 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:40 INFO - '_': '/tools/buildbot/bin/python'} 09:56:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:56:40 INFO - Running setup.py (path:/tmp/pip-kH9i0T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:56:40 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)) 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:56:40 INFO - Running setup.py (path:/tmp/pip-M_FkgM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:56:40 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)) 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:56:40 INFO - Running setup.py (path:/tmp/pip-3XNX8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:56:40 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)) 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:56:40 INFO - Running setup.py (path:/tmp/pip-wXSuWZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:56:40 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)) 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:56:40 INFO - Running setup.py (path:/tmp/pip-si2Fgm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:56:40 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)) 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:56:40 INFO - Running setup.py (path:/tmp/pip-x5QTLB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:56:40 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)) 09:56:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:56:40 INFO - Running setup.py (path:/tmp/pip-7OwMuH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:56:41 INFO - Running setup.py (path:/tmp/pip-lONiXf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:56:41 INFO - Running setup.py (path:/tmp/pip-1F1gjX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:56:41 INFO - Running setup.py (path:/tmp/pip-EtWT3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:56:41 INFO - Running setup.py (path:/tmp/pip-ICjZ0b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:56:41 INFO - Running setup.py (path:/tmp/pip-mSznPs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:56:41 INFO - Running setup.py (path:/tmp/pip-bLi8a9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:56:41 INFO - Running setup.py (path:/tmp/pip-DibI0Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:41 INFO - Running setup.py (path:/tmp/pip-kRc9h1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:56:41 INFO - Running setup.py (path:/tmp/pip-otDpVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:56:41 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)) 09:56:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:56:41 INFO - Running setup.py (path:/tmp/pip-gXNbqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:56:41 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)) 09:56:41 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)) 09:56:41 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)) 09:56:41 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)) 09:56:41 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)) 09:56:41 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)) 09:56:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:56:41 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 09:56:41 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 09:56:41 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 09:56:41 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 09:56:41 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 09:56:41 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 09:56:43 INFO - Downloading blessings-1.6.tar.gz 09:56:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:56:43 INFO - Installing collected packages: blessings 09:56:43 INFO - Running setup.py install for blessings 09:56:43 INFO - Successfully installed blessings 09:56:43 INFO - Cleaning up... 09:56:43 INFO - Return code: 0 09:56:43 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:56:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:44 INFO - Reading from file tmpfile_stdout 09:56:44 INFO - Current package versions: 09:56:44 INFO - argparse == 1.2.1 09:56:44 INFO - blessings == 1.6 09:56:44 INFO - blobuploader == 1.2.4 09:56:44 INFO - docopt == 0.6.1 09:56:44 INFO - functools32 == 3.2.3-2 09:56:44 INFO - jsonschema == 2.5.1 09:56:44 INFO - manifestparser == 1.1 09:56:44 INFO - mozInstall == 1.12 09:56:44 INFO - mozcrash == 0.17 09:56:44 INFO - mozdebug == 0.1 09:56:44 INFO - mozdevice == 0.48 09:56:44 INFO - mozfile == 1.2 09:56:44 INFO - mozhttpd == 0.7 09:56:44 INFO - mozinfo == 0.9 09:56:44 INFO - mozleak == 0.1 09:56:44 INFO - mozlog == 3.2 09:56:44 INFO - moznetwork == 0.27 09:56:44 INFO - mozprocess == 0.22 09:56:44 INFO - mozprofile == 0.28 09:56:44 INFO - mozrunner == 6.11 09:56:44 INFO - mozscreenshot == 0.1 09:56:44 INFO - mozsystemmonitor == 0.3 09:56:44 INFO - moztest == 0.7 09:56:44 INFO - mozversion == 1.4 09:56:44 INFO - psutil == 3.1.1 09:56:44 INFO - requests == 1.2.3 09:56:44 INFO - wsgiref == 0.1.2 09:56:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:56:44 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')]} 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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')]} 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:44 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 09:56:44 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 09:56:44 INFO - Using env: {'DISPLAY': ':0', 09:56:44 INFO - 'HOME': '/home/cltbld', 09:56:44 INFO - 'LANG': 'en_US.UTF-8', 09:56:44 INFO - 'LANGUAGE': 'en_US:en', 09:56:44 INFO - 'LOGNAME': 'cltbld', 09:56:44 INFO - 'MAIL': '/var/mail/cltbld', 09:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:44 INFO - 'MOZ_NO_REMOTE': '1', 09:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:44 INFO - 'NO_EM_RESTART': '1', 09:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:44 INFO - 'PWD': '/builds/slave/test', 09:56:44 INFO - 'SHELL': '/bin/bash', 09:56:44 INFO - 'SHLVL': '1', 09:56:44 INFO - 'TERM': 'linux', 09:56:44 INFO - 'TMOUT': '86400', 09:56:44 INFO - 'USER': 'cltbld', 09:56:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:44 INFO - '_': '/tools/buildbot/bin/python'} 09:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 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)) 09:56:44 INFO - Cleaning up... 09:56:44 INFO - Return code: 0 09:56:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:56:44 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')]} 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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')]} 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:44 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 09:56:44 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 09:56:44 INFO - Using env: {'DISPLAY': ':0', 09:56:44 INFO - 'HOME': '/home/cltbld', 09:56:44 INFO - 'LANG': 'en_US.UTF-8', 09:56:44 INFO - 'LANGUAGE': 'en_US:en', 09:56:44 INFO - 'LOGNAME': 'cltbld', 09:56:44 INFO - 'MAIL': '/var/mail/cltbld', 09:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:44 INFO - 'MOZ_NO_REMOTE': '1', 09:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:44 INFO - 'NO_EM_RESTART': '1', 09:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:44 INFO - 'PWD': '/builds/slave/test', 09:56:44 INFO - 'SHELL': '/bin/bash', 09:56:44 INFO - 'SHLVL': '1', 09:56:44 INFO - 'TERM': 'linux', 09:56:44 INFO - 'TMOUT': '86400', 09:56:44 INFO - 'USER': 'cltbld', 09:56:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:44 INFO - '_': '/tools/buildbot/bin/python'} 09:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:56:44 INFO - Cleaning up... 09:56:44 INFO - Return code: 0 09:56:44 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:56:44 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')]} 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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')]} 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:56:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:56:44 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 0x7f16eec0cf10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25258a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25f0350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, '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': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', '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 09:56:44 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 09:56:44 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 09:56:44 INFO - Using env: {'DISPLAY': ':0', 09:56:44 INFO - 'HOME': '/home/cltbld', 09:56:44 INFO - 'LANG': 'en_US.UTF-8', 09:56:44 INFO - 'LANGUAGE': 'en_US:en', 09:56:44 INFO - 'LOGNAME': 'cltbld', 09:56:44 INFO - 'MAIL': '/var/mail/cltbld', 09:56:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:56:44 INFO - 'MOZ_NO_REMOTE': '1', 09:56:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:56:44 INFO - 'NO_EM_RESTART': '1', 09:56:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:56:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:56:44 INFO - 'PWD': '/builds/slave/test', 09:56:44 INFO - 'SHELL': '/bin/bash', 09:56:44 INFO - 'SHLVL': '1', 09:56:44 INFO - 'TERM': 'linux', 09:56:44 INFO - 'TMOUT': '86400', 09:56:44 INFO - 'USER': 'cltbld', 09:56:44 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:56:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:56:44 INFO - '_': '/tools/buildbot/bin/python'} 09:56:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:56:44 INFO - Downloading/unpacking flake8 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:46 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:56:46 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56:46 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:56:46 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56: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 09:56:47 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:56:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:56:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:56:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:56:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:47 INFO - no previously-included directories found matching 'docs/_build' 09:56:47 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:56:47 INFO - Running setup.py install for pep8 09:56:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:56:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:56:47 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:56:47 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:56:47 INFO - no previously-included directories found matching 'docs/_build' 09:56:47 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:56:47 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:56:47 INFO - Cleaning up... 09:56:47 INFO - Return code: 0 09:56:47 INFO - Running post-action listener: _resource_record_post_action 09:56:47 INFO - Running post-action listener: _start_resource_monitoring 09:56:47 INFO - Starting resource monitoring. 09:56:47 INFO - ##### 09:56:47 INFO - ##### Finished create-virtualenv step (success) 09:56:47 INFO - ##### 09:56:47 INFO - ##### 09:56:47 INFO - ##### Running install step. 09:56:47 INFO - ##### 09:56:47 INFO - Running pre-action listener: _resource_record_pre_action 09:56:47 INFO - Running main action method: install 09:56:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:56:47 INFO - Reading from file tmpfile_stdout 09:56:47 INFO - Detecting whether we're running mozinstall >=1.0... 09:56:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:56:47 INFO - Reading from file tmpfile_stdout 09:56:47 INFO - Output received: 09:56:47 INFO - Usage: mozinstall [options] installer 09:56:47 INFO - Options: 09:56:47 INFO - -h, --help show this help message and exit 09:56:47 INFO - -d DEST, --destination=DEST 09:56:47 INFO - Directory to install application into. [default: 09:56:47 INFO - "/builds/slave/test"] 09:56:47 INFO - --app=APP Application being installed. [default: firefox] 09:56:47 INFO - mkdir: /builds/slave/test/build/application 09:56:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 09:56:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 09:57:04 INFO - Reading from file tmpfile_stdout 09:57:04 INFO - Output received: 09:57:04 INFO - /builds/slave/test/build/application/firefox/firefox 09:57:04 INFO - Running post-action listener: _resource_record_post_action 09:57:04 INFO - ##### 09:57:04 INFO - ##### Finished install step (success) 09:57:04 INFO - ##### 09:57:04 INFO - ##### 09:57:04 INFO - ##### Running run-tests step. 09:57:04 INFO - ##### 09:57:04 INFO - Running pre-action listener: _resource_record_pre_action 09:57:04 INFO - Running main action method: run_tests 09:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:57:04 INFO - Python 2.7.3 09:57:04 INFO - Return code: 0 09:57:04 INFO - grabbing minidump binary from tooltool 09:57:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:57:04 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 0x267f1c0>, '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 0x26800c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x2680550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:57:04 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 09:57:04 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 09:57:04 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 09:57:04 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:57:04 INFO - Return code: 0 09:57:04 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 09:57:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:57:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:57:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 09:57:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:57:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:57:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:57:05 INFO - Return code: 0 09:57:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:57:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-057 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:57:05 INFO - Using env: {'DISPLAY': ':0', 09:57:05 INFO - 'HOME': '/home/cltbld', 09:57:05 INFO - 'LANG': 'en_US.UTF-8', 09:57:05 INFO - 'LANGUAGE': 'en_US:en', 09:57:05 INFO - 'LOGNAME': 'cltbld', 09:57:05 INFO - 'MAIL': '/var/mail/cltbld', 09:57:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:57:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 09:57:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:05 INFO - 'MOZ_NO_REMOTE': '1', 09:57:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:57:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 09:57:05 INFO - 'NO_EM_RESTART': '1', 09:57:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 09:57:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:05 INFO - 'PWD': '/builds/slave/test', 09:57:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:57:05 INFO - 'SHELL': '/bin/bash', 09:57:05 INFO - 'SHLVL': '1', 09:57:05 INFO - 'TERM': 'linux', 09:57:05 INFO - 'TMOUT': '86400', 09:57:05 INFO - 'USER': 'cltbld', 09:57:05 INFO - 'XDG_SESSION_COOKIE': '934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003', 09:57:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:05 INFO - '_': '/tools/buildbot/bin/python'} 09:57:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-057', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:57:06 INFO - mozversion application_buildid: 20160607070918 09:57:06 INFO - mozversion application_changeset: 448365b11e377ebf1c572d54e45bbd36c45af03a 09:57:06 INFO - mozversion application_display_name: Nightly 09:57:06 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:57:06 INFO - mozversion application_name: Firefox 09:57:06 INFO - mozversion application_remotingname: firefox 09:57:06 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:57:06 INFO - mozversion application_vendor: Mozilla 09:57:06 INFO - mozversion application_version: 50.0a1 09:57:06 INFO - mozversion platform_buildid: 20160607070918 09:57:06 INFO - mozversion platform_changeset: 448365b11e377ebf1c572d54e45bbd36c45af03a 09:57:06 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 09:57:06 INFO - mozversion platform_version: 50.0a1 09:57:06 INFO - using testdate: 1465318626 09:57:06 INFO - actual date: 1465318626 09:57:06 INFO - starting webserver on 'localhost:43115' 09:57:06 INFO - SUITE-START | Running 2 tests 09:57:06 INFO - TEST-START | dromaeo_css 09:57:06 INFO - operating with platform_type : linux_ 09:57:06 INFO - Initialising browser for dromaeo_css test... 09:57:06 INFO - TEST-INFO | started process 2206 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwZFtW2/profile http://localhost:43115/getInfo.html) 09:57:09 INFO - PROCESS | 2206 | __metrics Screen width/height:1600/1200 09:57:09 INFO - PROCESS | 2206 | colorDepth:24 09:57:09 INFO - PROCESS | 2206 | Browser inner width/height: 1024/697 09:57:09 INFO - PROCESS | 2206 | __metrics 09:57:10 ERROR - PROCESS | 2206 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:57:11 INFO - PROCESS | 2206 | console.error: PushService: 09:57:11 INFO - PROCESS | 2206 | stateChangeProcessEnqueue: Error transitioning state 09:57:11 INFO - PROCESS | 2206 | UnknownError 09:57:11 INFO - TEST-INFO | 2206: exit 0 09:57:11 INFO - Browser initialized. 09:57:11 INFO - Running cycle 1/1 for dromaeo_css test... 09:57:11 INFO - TEST-INFO | started process 2290 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpwZFtW2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:57:23 INFO - PROCESS | 2290 | RSS: Main: 156512256 09:57:23 INFO - PROCESS | 2290 | 09:58:14 INFO - PROCESS | 2290 | 1465318694861 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] 10:00:23 INFO - PROCESS | 2290 | Cycle 1(1): loaded http://localhost:43115/tests/dromaeo/cssquery-dojo.html (next: http://localhost:43115/tests/dromaeo/cssquery-ext.html) 10:00:23 INFO - PROCESS | 2290 | RSS: Main: 254828544 10:00:23 INFO - PROCESS | 2290 | 10:03:26 INFO - PROCESS | 2290 | Cycle 1(1): loaded http://localhost:43115/tests/dromaeo/cssquery-ext.html (next: http://localhost:43115/tests/dromaeo/cssquery-jquery.html) 10:03:26 INFO - PROCESS | 2290 | RSS: Main: 235757568 10:03:26 INFO - PROCESS | 2290 | 10:06:28 INFO - PROCESS | 2290 | Cycle 1(1): loaded http://localhost:43115/tests/dromaeo/cssquery-jquery.html (next: http://localhost:43115/tests/dromaeo/cssquery-mootools.html) 10:06:29 INFO - PROCESS | 2290 | RSS: Main: 246079488 10:06:29 INFO - PROCESS | 2290 | 10:09:31 INFO - PROCESS | 2290 | Cycle 1(1): loaded http://localhost:43115/tests/dromaeo/cssquery-mootools.html (next: http://localhost:43115/tests/dromaeo/cssquery-prototype.html) 10:09:32 INFO - PROCESS | 2290 | RSS: Main: 238063616 10:09:32 INFO - PROCESS | 2290 | 10:12:34 INFO - PROCESS | 2290 | Cycle 1(1): loaded http://localhost:43115/tests/dromaeo/cssquery-prototype.html (next: http://localhost:43115/tests/dromaeo/cssquery-yui.html) 10:12:35 INFO - PROCESS | 2290 | RSS: Main: 222474240 10:12:35 INFO - PROCESS | 2290 | 10:15:37 INFO - PROCESS | 2290 | Cycle 1(1): loaded http://localhost:43115/tests/dromaeo/cssquery-yui.html (next: http://localhost:43115/tests/dromaeo/cssquery-dojo.html) 10:15:38 INFO - PROCESS | 2290 | RSS: Main: 292524032 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | __start_tp_report 10:15:38 INFO - PROCESS | 2290 | _x_x_mozilla_page_load 10:15:38 INFO - PROCESS | 2290 | _x_x_mozilla_page_load_details 10:15:38 INFO - PROCESS | 2290 | |i|pagename|runs| 10:15:38 INFO - PROCESS | 2290 | |0;dojo.html;3107.3940581586585;3112.1597168764433;3133.1070644866204;3164.968350316496;3191.8084914905107;5176.741162941857;5252.054630166568;5314.415414304428;5386.326709161355;5412.91880621791;5298.523132918037;5306.177542480912;5322.2282768998475;5385.246065550826;5386.2997810284605;4832.468428472856;4875.488073752258;4882.829100981467;4890.315355850184;5017.397912250535;4969.652124351297;4980.128477516439;5013.874653133665;5025.522575355354;5034.169362055257;4748.786304616283;4786.736729479877;4835.830745923893;4871.391076115486;4909.3126962225315;4910.435299940516;4925.482725089799;4926.3595732554895;4926.655134140611;4960.131976904064;4684.180268453007;4887.437944636376;4909.631777616693;4910.484399138075;4923.606111511071;5239.73801309933;5303.7082960437165;5316.548093412064;5407.810726624549;5410.458954104597;5805.593608447411;5813.47678708914;5823.621464604787;5823.82528524145;5826.650400975913;5538.6399884007415;5619.6066275360745;5806.129080637896;5844.152597873284;5852.151438041503;5313.335833020872;5368.516833485005;5414.593905457106;5418.647787893774;5421.5391691706645;5904.380040095814;5907.881842363215;5913.231279933667;5913.940860591425;5924.14099955504;5436.347638283352;5572.832815015556;5641.774329026793;5692;5693.259876216065;5789.421057894261;5792.565557583198;5833.416658334218;5842.123681447817;5848.912266316009;5703.315602127771;5739.2252045973255;5747.08046712524;5754.568407369464;5759.596828222068;5440.455954404607;5585.357683866408;5605.159226116034;5654.491095801315;5764.884702305931;4214.789260536961;4215.936760948527;4216.2832318505925;4217.34631132175;4250.3199488081755;3800.790956497364;3804.1440675848266;3806.0484878780303;3813.389857622767;3834.8082595870096;5243.344581927259;5304.973475132677;5333.306670132858;5366.892662146735;5370.892582148335;5737.5983681141915;5738.024535828919;5741.62679425836;5747.3103227612955;5774.68239246837;5363.061464243741;5406.270153529302;5412.269343638637;5413.864653383697;5417.3770016447825;4825.1555977703765;4852.660313778072;4888.462269150391;4915.65590408668;4971.502849715;5861.3552509223955;5863.853403664943;5865.061590145641;5873.676947767828;5874.853128671817;5706.2011318414625;5733.111367738007;5733.226014487991;5736.71316434185;5737.168110624019;5756.194132821324;5827.4172582741385;5875.559333049953;5879.235699359056;5914.349421563538;5642.492175704207;5646.520045795989;5692.260006199168;5692.658440493584;5697.686627235542;5603.971980140073;5771.422857714195;5783.19035335045;5785.39253378406;5789.189513468202;5500.669959802424;5567.359753628222;5602.2717046783655;5640.041192997282;5647.068233741551;5623.465770751772;5632.324121105494;5645.294338207724;5648.068068768607;5652.7173641318595;5298.8940221196135;5423.484768946943;5512.6968016759465;5515.062439385394;5526.088195447706;5670.943290567042;5760.740766665594;5765.5675824314185;5767.077267637158;5771.769129234784;5587.776488940397;5587.944120558905;5591;5605.04714198579;5629.352624448241 10:15:38 INFO - PROCESS | 2290 | |1;ext.html;596.296370283066;628.2021832273012;628.6762317406534;635.8092572228331;648.7988723495716;32526.349473010527;34778.65221347786;34836;34860.30279394411;34863.302733945304;47926.52073479264;49491.50508494923;49527.50472495274;49562.25656615154;49569.25646115302;8705.912940870605;8915.465072095694;8916.375853690211;8920.776980575472;8921.01868794432;8779.824403511926;8906.242969347613;8907.821843563124;8908;8908.8663670045;8825.382223244376;8861.335399845006;8873.73378798637;8880.555972201364;8912.688055918044;8766.736997890073;8865.734027979172;8866.822663546758;8872.334574906909;8877.911220887772;8669.306455483546;8709.7822554436;8718.651253949834;8752.956235218848;8761.956190219073;41864.1627167458;42148.78925605383;42198.78900605477;42347.36478952818;42384.36423453651;3376.4428869236544;3461.238527523941;3649.2883887641656;3992.40113982902;3998.9400158997637;32529.837350813094;32777.01668949935;32867.17832054194;33031.3393732124;33525.32949341;4240.109576988836;4351.412559304516;4383.583559561836;4480.283154695233;4519.525449827786;341162;341939;343387.9999999975;346319;346448;51475.485245147815;51817;52487.212691809655;52545;52581.2112818308;62007.379926201065;62098;62141;62210.37789622136;62213.68893155596;17042.403515876886;17312.307507699803;17346.65306693859;17357.73963390525;17359.30562777475;12645.93677031609;12753.426095825711;12756.744865102832;12761.68095797594;12761.808572871414;14899.255037248311;16526.173691315387;16798.580035498966;16807.831921680627;16858;5645.717714114277;6617.305182955816;6771.52599318043;6881.483888708367;7051.753188638476;1049.7612816876008;1072.7747173093737;1096.52849274811;1125.4597793059213;1177.8645455772526;31083.667244782173;31578;31753.523697144563;32338.35323293521;32461;1113.1282484892258;1161.9709507262387;1192.4881224792903;1205.4816428935642;1258.974820503603;17146.485605431855;17319.480415587554;17339.65320693579;17340.219690113976;17372.47882563525;504372;507031;509912;510888;513474;59244.40755592475;59257.40742592605;59271.11093333604;59380.406195938354;59423.702881486184;1292.5476083370745;1298.7597294500547;1349.6221058103747;1405.4588983241526;1426.415169780385;32192.517112243804;33897.322053559285;33972.49041264432;34271.657283426845;34601.307973840885;53594.73202633962;55215;55278.72360638171;61134;61262.69368653128;438170;446707;447196;448509;448659;24718.382040448414;26846.194614161606;27465.862670686518;27585.310367240978;29741;47695.28457073077;48140.518594815;48168.5183148164;49201.75399123125;49235.753821232094;41478.792606036775;41554.37668435036;42137.1572568553;42855;42919.57080429156;40733.59266407298;40828.79585602053;41017.31062455805;41835;42216.36675449809;40630.59369406268;41652.58347416487;41856;41882.37176442417;41904.58095419007;35153.64846351504;36708.632913670524;36843.63156368509;37020;37256.81371593125;27933;28078.2980425491;28086;28199.577006344512;28303 10:15:38 INFO - PROCESS | 2290 | |2;jquery.html;382.6154714511916;392.4055056589267;393.307778310174;393.5640699154994;401.3297792686214;17337.913310433447;18745.15646795894;18775.90612046938;18810.62378752426;20086.196552137895;19058.809411905877;19135;19351.70972435411;19396.418107456764;19604.411867643958;5512.641678290898;5573.777048918038;5655.236543066695;5668.43315668434;5687.630304030225;5503.9174412383645;5596.272484576999;5603.80386686466;5606.6636001839815;5611.9438805612035;5456;5552.194931734896;5579.69311687857;5720.027595308787;5720.685362305072;5719.59962802604;5754.992876246661;5759.999999999958;5762.855928601818;5782.45066718661;5376.327959005124;5633.57748168889;5638.210650508932;5638.4361563843695;5644.689542075184;13018.218906865524;13394;13445;13735.107218030797;14151.292435378293;2225.5215128747395;2289.6371103747847;2452.9877350613133;2514.786243169333;2774.0706863200853;9901.504924753734;10111.494425278706;10352.896471035343;13762.449502019907;14071.788923166161;1794.4168145352812;1852.397970659541;1883.5197024758736;1891.7162425636127;1895.1471837672964;79920;81763;82567;82797.58601207015;83281;10994.120470362512;11106.555737770399;11117.666470005914;11144.764989304174;11491.54033838637;15203.543893683207;15344.156071415957;15348;15384.461543846135;15421.383144674312;8011.319037881729;8255.752327430188;8287.62705678246;8477.788055298544;8481.406301558955;7974.162712915177;8017.4387792855105;8097.595120244082;8220.219079187467;8287.50274983503;4693.24908014716;4719.244920812654;4738.549837765407;4817.710937343771;4850.223964165716;1973.190991693399;2003.4590660521578;2003.9799602003793;2015.61703276377;2049.108637742587;963.604921981984;966.3139171188394;975.2588033094898;1000.2598077422655;1020.0921180149672;18807.529811754815;19132.61734765297;19280.132394042022;19344.516387090436;19450.60433523113;964.5900492290748;975.2588033094898;1000.2448151645461;1000.849872519128;1008.7679833638305;10154.695359139238;10852.565897364017;10880.67357979262;10917.39954302521;10949.83575246372;476766;478906;479697;484473;487797;57638;76691;101315.49342253241;101681.49159254157;103737;998.4658207858906;1033.540074666781;1049.6431213387332;1069.4920162570893;1074.2641290715885;17415.738763918296;17465.126743662513;17900.462986110437;18022.279108836025;18267.90866045661;42067.00363465466;42090.57909420867;42752.358714618684;44022.339664905696;44070;58932;59026.11460828177;60722;61060;61500.69249653723;8451.8732219018;8620.870686939827;8687.131286871263;8832.116788321116;9121.726348209564;10769.369171256434;13299.401526931118;13528.120672156278;13723.656908577366;13740.10689305192;11289.379084150121;11639.5926142588;11736.823947640964;11953.462094205608;11982.221155624857;11332.773344532898;11419;11638.592649257575;12019.75960480768;12169.756604868031;11237.64009558002;11337.093032557541;11590.768184636092;11670.649880503599;11720.472578734149;8243.216894395024;8301.750947471586;8314.168583141638;8402.41183117176;8419.452735572168;10495.160387168855;11238.10095192399;11279.492422840827;11340.489677964677;11488.770224595293 10:15:38 INFO - PROCESS | 2290 | |3;mootools.html;1380.6548362909273;1507.3669058995224;1526.6565022869854;1656.3291866793945;1829.6889528780114;8195.22145396188;8409.201125893038;8429.199226073528;8871.37900346976;8982.86525702115;8574.785630359243;8793.648254069829;8794.736157915257;8799.076097009818;8809.427387219826;2016.526116362661;2144.4424449643047;2193.462601662595;2277.9202727904485;2319.118734880743;2265.456290490287;2303.608386574286;2306.3888069661552;2307.4462129088984;2309.4341886237894;2275.772422757719;2276.237460450751;2282.611955967481;2296.1619009061656;2298.1267118494948;1845.6031953130075;2169.3383518026994;2184.9890750546306;2231.3640612425543;2252.245497758253;2269.251147121446;2281.6691579721014;2289.553537060274;2295.426143464134;2295.4490922178725;8763.12368763125;8804.559772011438;8818.955905220497;8820.514871682055;8824.735257942271;4972.129877271499;5096.745162741848;5105.132127538327;5105.157648987913;5106.872328191787;3047.3752880659413;3164.3196712706876;3203.4073696366013;3207.8556464959133;3322.8172450515212;1034.984475232872;1036.046836910037;1036.556417154612;1039.8960103989616;1062.6015244283394;12163.148579599432;12373.319467429288;12379.876201238052;12390.504379824797;12408.56570020045;4372.3878656988045;4406.691531592822;4434.312681534368;4464.888377790581;4566.680332376702;10379.84430233547;10414.218933580012;10419.166466682647;10424.478776061167;10427.530761115768;5252.44849290819;5257.737113144404;5259.368875734936;5261;5261.447548007481;4155.3143731284;4672.602828759594;4787.3537072495465;4808.230683090689;4872.756362181876;3879.728419010642;3920.862769803043;3997.420374045747;4024.7182697210915;4040.818163182605;1199.6161228407;1203.2359451748202;1211.1461419699092;1213.6177104212145;1213.787587172259;853.2193043365252;862.9137086291446;883.6642076011075;888.6223355074068;966.9371490853072;5841.561882858718;5889.587728858939;5896.793612223637;6105.267367915879;6114.7248373822395;990.321629683669;1003.3979612232748;1013.5388398278766;1038.5378506564657;1117.9776404471866;4085.9795701021303;4143.96400899775;4198.790060496963;4253.085586598896;4261.74429534229;9901.108900199017;10007.99920008008;10069.647562335284;10132.138768204784;10147.188224941987;10003.34978226413;10029.899701002896;10084.747881303027;10091.848622270672;10096.899031009743;7978.122406535276;8042.678292868337;8102.351811855037;8107.108218096005;8127.1466496017065;6066.848328791815;6076.1493390924425;6133.3866613338305;6199.411055949678;6219.782307619212;9841.360311579725;10169.338992965433;10173.389596624227;10182;10183.694489165337;10080.04239597237;10231.13035392;10521.316114452537;10542.420166890895;10614.203934704774;6937.965310173417;7106.324899134574;7108.644567771694;7464.477486576105;7653.043369578802;7970.960145199237;8154.959225203836;8406.915930840858;8416.452930559493;8678.913210867811;7150.5352152109945;7271.272872712686;7341.926580734124;7354.77935661931;7434.73978410753;7319;7418.703251869865;7476.813079672834;7593.468457207942;7646.120696120018;7087.751928682472;7091.574505529685;7126;7138.179109402485;7152.642367881689;4921.704697718148;4938.827141050046;5016.698998060128;5075.619328550448;5093.516115968977;5057.241413787961;5097.566706829961;5147.459516750837;5173.1205695031185;5229.241759944711 10:15:38 INFO - PROCESS | 2290 | |4;prototype.html;326.74840372912854;330.2009137886315;333.0939843625339;343.1250311705151;346.45260488428283;5204.817831375903;5228.562145410009;5232.979568984045;5237.7381130943395;5248.6063545234065;4968.826091086813;4978.352814134158;5086.491350864921;5225.667454799031;5230.163173892178;4109.486314210723;4135.462389889325;4143.19207754488;4144.8549300774475;4179.623833854953;4122.515894278057;4150.418941348214;4162.895927601804;4170.916581668364;4181.205570941512;3815.2560250751117;3875.7286989910717;4015.7791321477307;4117.588241175873;4154.8130334134885;4080.3879418087226;4129.958700413017;4139.834406623747;4144.854930077463;4146.3780432935;3656.981715091434;3658.8536458541626;3662.1393972416463;3665.541807274091;3681.245344704329;4983.526564976321;4997.90004199914;5007.474215207387;5036.748162591855;5055.949440505621;4633.721976681376;4653.325267836145;4664.626829853603;4691.671582989192;4693;5396.43337449566;5408.702521361323;5416;5422.864428389283;5430.239766432703;3942.56631770505;3997.440998010778;4006.1587066716024;4013.5350597032166;4036.2129384769983;5571.387147413781;5592.3009623797025;5603.691796951166;5616.157576363537;5621.241132447149;4119.073208528117;4130.690198235145;4148.688848336386;4158.500979882373;4165.75005499671;4013.7190396671954;4177.3316269396755;4177.4778152730905;4191.3293872980785;4210.263203939359;4625.167469855458;4671.159191345522;4705.858824235278;4708.88227794301;4717.669763116549;5407.783688652488;5409.756560954766;5414.945850541523;5513.889722205534;5524.596800391842;1371.4445649511765;1397.1407584335764;1418.7446259673359;1433.7634290341978;1445.3134760988446;984.8178087053918;1013.7364285285879;1020.6886899495577;1021.8402113601117;1034.462079718542;5178.456262092502;5182.24857395683;5231.738413079331;5306.734663266821;5310.2831117798605;5455.236266922634;5514.476124768141;5586.888262234733;5604.915926261109;5623.441334192932;5030.647854650139;5167.560757335668;5245.527902488794;5285.867853303621;5289.444608316148;2918.5038543447663;2920.0801747449486;2948.4987552115763;2951.9409611808073;2968.391479746647;5602.271704678447;5619.904118696897;5653.689047102366;5679.517241034476;5731.455511726379;5386.003589335985;5619.634723742944;5625;5630.155476678531;5668.603197776199;5690.800821971211;5701.543876489871;5708.229389032427;5713.22871412354;5720;5290.629655924048;5546.584006199632;5548.556115510669;5576.91634625489;5608.158776183605;5548.389677135593;5614.848955964099;5616.269884915098;5619.269494965628;5638.731285460739;5794.739236734273;5821.359650438533;5826.82519524415;5852.887951289242;5862.443067908542;3863.517060367454;3929.4498770171626;3936.3111455495746;4038.959610403976;4060.309747343018;5611.158326250932;5619.943800561942;5629.183768353484;5644.153376993484;5659.575531835211;5279.023380674587;5373.489518495737;5449.782008719765;5472.480114389126;5552;5456.863578410572;5502.477264659851;5505.2933590587045;5533.280673512418;5567.860803479945;5181.715005674724;5356.812511562077;5403.405625381283;5425.376081750649;5469.972650136724;5436.6194366393975;5508.697021663686;5524.364698059614;5540.279763630702;5549.500544950813;5434.592405569519;5445.564354851523;5446.645968012067;5476.397596264487;5485.50630443262 10:15:38 INFO - PROCESS | 2290 | |5;yui.html;674.2616834566148;695.7147569496505;713.1513498936263;778.2956424435886;783.9176886426925;15863.920680396597;16330.265138068786;16482.83517164828;16576.834231657678;16703.91648041758;14985.250737463139;15063.924680376611;15169.54491365258;15177.317020734066;15202;684.7534887440518;800.2717527050386;817.8200795824924;828.2214718164936;833.8832563441123;830.8296799156159;835.4861760017607;837.1837458477966;838.0864857305536;838.2246422059601;867.3581549653275;867.5011868175793;868.0278088540844;868.9391742578022;869.0527325215514;715.8496715689712;826.5247482697444;861.4314552395451;864.7319331007376;867.8784970104191;628.9088082228097;629.496402877696;630.0517720830121;631.0124654915044;631.4821846086211;14992.100473971597;15038.473653422077;15041.247937603077;15055.096694198382;15101.773473397909;14274.857251427457;14501;14666.779998300033;14702.117872927658;14929.104253744701;12891.548795792196;13078.149920255248;13085.738285234242;13118.803217951738;13412.664683382896;4401.757903315316;4418.536053714346;4595.540445955411;4698.718076915362;4701.012787314634;73418;73573;74131;74148.6292568539;75917.99999999945;11297.26567773092;11333.716657083474;11684.065274777997;11717.355545445082;11737.589184378676;20234.494137646678;20369.89815050945;20397.79602203959;20527.692084618742;20572.58854822925;8407.369447291478;8552.358573107042;8758.781030474289;8914.598843051948;9007.414518056305;4678.204705200124;4695.577398034194;4703.482616912137;4739.905201895943;4854.660173787801;3379.4930760386137;3537.5931767846514;3547.7693949893173;3871.7676939383164;4023.637872591481;664.4352300544499;666.1573110015834;668.4117976180929;675.4393853101914;679.8776220280397;707.1302298173173;731.9634018299064;739.7521830186947;740.9629518524052;752.450710980976;9882;9933.304668673123;10100.494975251208;10176.898231017743;10188.694339169837;618.7927044440073;649.389573800626;687.6500823592459;700.1528150937423;716.3266529462289;4493.820247190076;4576.222042252825;4639;4672.5327467253;4700.341952126705;73935.26064739283;74185;74235.62882185554;122890.38554807348;126045;102016.48991754993;102626.48686756518;102675.48662256642;102718.48640756898;104167.4791626037;88575.55712221526;89300;89362;89746;91776;8875.911240887508;8893.599788009684;8926.419782714103;8948.149925757043;9139.040400758091;49643.75178124086;50148.74925625348;50805.745971269906;51607.48392516177;55418.44581554133;71535.28464715286;71536.64231678809;74830;75992;77081;12926.482940682268;12932.806007909701;13023.739525209254;13510.86489135096;13550.525731599346;14456.20490873012;14643;15176.241187940337;15360.923195383952;15407.537773866801;13506.257155856521;13747.175169489863;14085.859141408864;14086.506972255922;14277.9291553136;12758.80974928981;12931.741365172831;12936.547220847226;13241.602751917459;13306.600801975956;13252.20486770797;13389.732205356033;13503.514613392716;13587;14214.431422742975;10956.830843220014;11187.272827266203;11282.887171128512;11287.492062857355;11599.420028998686;11186;11727.35499547533;11853.52585896589;11924.224925379822;12005.75988480208 10:15:38 INFO - PROCESS | 2290 | __end_tp_report 10:15:38 INFO - PROCESS | 2290 | __start_cc_report 10:15:38 INFO - PROCESS | 2290 | _x_x_mozilla_cycle_collect,867 10:15:38 INFO - PROCESS | 2290 | __end_cc_report 10:15:38 INFO - PROCESS | 2290 | __startTimestamp1465319738180__endTimestamp 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | ------- Summary: start ------- 10:15:38 INFO - PROCESS | 2290 | Number of tests: 6 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | [#0] dojo.html Cycles:165 Average:5320.80 Median:5526.09 stddev:616.16 (11.2%) stddev-sans-first:593.08 10:15:38 INFO - PROCESS | 2290 | Values: 3107.4 3112.2 3133.1 3165.0 3191.8 5176.7 5252.1 5314.4 5386.3 5412.9 5298.5 5306.2 5322.2 5385.2 5386.3 4832.5 4875.5 4882.8 4890.3 5017.4 4969.7 4980.1 5013.9 5025.5 5034.2 4748.8 4786.7 4835.8 4871.4 4909.3 4910.4 4925.5 4926.4 4926.7 4960.1 4684.2 4887.4 4909.6 4910.5 4923.6 5239.7 5303.7 5316.5 5407.8 5410.5 5805.6 5813.5 5823.6 5823.8 5826.7 5538.6 5619.6 5806.1 5844.2 5852.2 5313.3 5368.5 5414.6 5418.6 5421.5 5904.4 5907.9 5913.2 5913.9 5924.1 5436.3 5572.8 5641.8 5692.0 5693.3 5789.4 5792.6 5833.4 5842.1 5848.9 5703.3 5739.2 5747.1 5754.6 5759.6 5440.5 5585.4 5605.2 5654.5 5764.9 4214.8 4215.9 4216.3 4217.3 4250.3 3800.8 3804.1 3806.0 3813.4 3834.8 5243.3 5305.0 5333.3 5366.9 5370.9 5737.6 5738.0 5741.6 5747.3 5774.7 5363.1 5406.3 5412.3 5413.9 5417.4 4825.2 4852.7 4888.5 4915.7 4971.5 5861.4 5863.9 5865.1 5873.7 5874.9 5706.2 5733.1 5733.2 5736.7 5737.2 5756.2 5827.4 5875.6 5879.2 5914.3 5642.5 5646.5 5692.3 5692.7 5697.7 5604.0 5771.4 5783.2 5785.4 5789.2 5500.7 5567.4 5602.3 5640.0 5647.1 5623.5 5632.3 5645.3 5648.1 5652.7 5298.9 5423.5 5512.7 5515.1 5526.1 5670.9 5760.7 5765.6 5767.1 5771.8 5587.8 5587.9 5591.0 5605.0 5629.4 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | [#1] ext.html Cycles:180 Average:59626.44 Median:41893.48 stddev:116062.30 (277.0%) stddev-sans-first:116303.26 10:15:38 INFO - PROCESS | 2290 | Values: 596.3 628.2 628.7 635.8 648.8 32526.3 34778.7 34836.0 34860.3 34863.3 47926.5 49491.5 49527.5 49562.3 49569.3 8705.9 8915.5 8916.4 8920.8 8921.0 8779.8 8906.2 8907.8 8908.0 8908.9 8825.4 8861.3 8873.7 8880.6 8912.7 8766.7 8865.7 8866.8 8872.3 8877.9 8669.3 8709.8 8718.7 8753.0 8762.0 41864.2 42148.8 42198.8 42347.4 42384.4 3376.4 3461.2 3649.3 3992.4 3998.9 32529.8 32777.0 32867.2 33031.3 33525.3 4240.1 4351.4 4383.6 4480.3 4519.5 341162.0 341939.0 343388.0 346319.0 346448.0 51475.5 51817.0 52487.2 52545.0 52581.2 62007.4 62098.0 62141.0 62210.4 62213.7 17042.4 17312.3 17346.7 17357.7 17359.3 12645.9 12753.4 12756.7 12761.7 12761.8 14899.3 16526.2 16798.6 16807.8 16858.0 5645.7 6617.3 6771.5 6881.5 7051.8 1049.8 1072.8 1096.5 1125.5 1177.9 31083.7 31578.0 31753.5 32338.4 32461.0 1113.1 1162.0 1192.5 1205.5 1259.0 17146.5 17319.5 17339.7 17340.2 17372.5 504372.0 507031.0 509912.0 510888.0 513474.0 59244.4 59257.4 59271.1 59380.4 59423.7 1292.5 1298.8 1349.6 1405.5 1426.4 32192.5 33897.3 33972.5 34271.7 34601.3 53594.7 55215.0 55278.7 61134.0 61262.7 438170.0 446707.0 447196.0 448509.0 448659.0 24718.4 26846.2 27465.9 27585.3 29741.0 47695.3 48140.5 48168.5 49201.8 49235.8 41478.8 41554.4 42137.2 42855.0 42919.6 40733.6 40828.8 41017.3 41835.0 42216.4 40630.6 41652.6 41856.0 41882.4 41904.6 35153.6 36708.6 36843.6 37020.0 37256.8 27933.0 28078.3 28086.0 28199.6 28303.0 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | [#2] jquery.html Cycles:180 Average:28551.81 Median:2137.32 stddev:79499.18 (3719.6%) stddev-sans-first:79694.06 10:15:38 INFO - PROCESS | 2290 | Values: 382.6 392.4 393.3 393.6 401.3 17337.9 18745.2 18775.9 18810.6 20086.2 19058.8 19135.0 19351.7 19396.4 19604.4 5512.6 5573.8 5655.2 5668.4 5687.6 5503.9 5596.3 5603.8 5606.7 5611.9 5456.0 5552.2 5579.7 5720.0 5720.7 5719.6 5755.0 5760.0 5762.9 5782.5 5376.3 5633.6 5638.2 5638.4 5644.7 13018.2 13394.0 13445.0 13735.1 14151.3 2225.5 2289.6 2453.0 2514.8 2774.1 9901.5 10111.5 10352.9 13762.4 14071.8 1794.4 1852.4 1883.5 1891.7 1895.1 79920.0 81763.0 82567.0 82797.6 83281.0 10994.1 11106.6 11117.7 11144.8 11491.5 15203.5 15344.2 15348.0 15384.5 15421.4 8011.3 8255.8 8287.6 8477.8 8481.4 7974.2 8017.4 8097.6 8220.2 8287.5 4693.2 4719.2 4738.5 4817.7 4850.2 1973.2 2003.5 2004.0 2015.6 2049.1 963.6 966.3 975.3 1000.3 1020.1 18807.5 19132.6 19280.1 19344.5 19450.6 964.6 975.3 1000.2 1000.8 1008.8 10154.7 10852.6 10880.7 10917.4 10949.8 476766.0 478906.0 479697.0 484473.0 487797.0 57638.0 76691.0 101315.5 101681.5 103737.0 998.5 1033.5 1049.6 1069.5 1074.3 17415.7 17465.1 17900.5 18022.3 18267.9 42067.0 42090.6 42752.4 44022.3 44070.0 58932.0 59026.1 60722.0 61060.0 61500.7 8451.9 8620.9 8687.1 8832.1 9121.7 10769.4 13299.4 13528.1 13723.7 13740.1 11289.4 11639.6 11736.8 11953.5 11982.2 11332.8 11419.0 11638.6 12019.8 12169.8 11237.6 11337.1 11590.8 11670.6 11720.5 8243.2 8301.8 8314.2 8402.4 8419.5 10495.2 11238.1 11279.5 11340.5 11488.8 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | [#3] mootools.html Cycles:180 Average:5697.01 Median:4449.60 stddev:3240.13 (72.8%) stddev-sans-first:3232.98 10:15:38 INFO - PROCESS | 2290 | Values: 1380.7 1507.4 1526.7 1656.3 1829.7 8195.2 8409.2 8429.2 8871.4 8982.9 8574.8 8793.6 8794.7 8799.1 8809.4 2016.5 2144.4 2193.5 2277.9 2319.1 2265.5 2303.6 2306.4 2307.4 2309.4 2275.8 2276.2 2282.6 2296.2 2298.1 1845.6 2169.3 2185.0 2231.4 2252.2 2269.3 2281.7 2289.6 2295.4 2295.4 8763.1 8804.6 8819.0 8820.5 8824.7 4972.1 5096.7 5105.1 5105.2 5106.9 3047.4 3164.3 3203.4 3207.9 3322.8 1035.0 1036.0 1036.6 1039.9 1062.6 12163.1 12373.3 12379.9 12390.5 12408.6 4372.4 4406.7 4434.3 4464.9 4566.7 10379.8 10414.2 10419.2 10424.5 10427.5 5252.4 5257.7 5259.4 5261.0 5261.4 4155.3 4672.6 4787.4 4808.2 4872.8 3879.7 3920.9 3997.4 4024.7 4040.8 1199.6 1203.2 1211.1 1213.6 1213.8 853.2 862.9 883.7 888.6 966.9 5841.6 5889.6 5896.8 6105.3 6114.7 990.3 1003.4 1013.5 1038.5 1118.0 4086.0 4144.0 4198.8 4253.1 4261.7 9901.1 10008.0 10069.6 10132.1 10147.2 10003.3 10029.9 10084.7 10091.8 10096.9 7978.1 8042.7 8102.4 8107.1 8127.1 6066.8 6076.1 6133.4 6199.4 6219.8 9841.4 10169.3 10173.4 10182.0 10183.7 10080.0 10231.1 10521.3 10542.4 10614.2 6938.0 7106.3 7108.6 7464.5 7653.0 7971.0 8155.0 8406.9 8416.5 8678.9 7150.5 7271.3 7341.9 7354.8 7434.7 7319.0 7418.7 7476.8 7593.5 7646.1 7087.8 7091.6 7126.0 7138.2 7152.6 4921.7 4938.8 5016.7 5075.6 5093.5 5057.2 5097.6 5147.5 5173.1 5229.2 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | [#4] prototype.html Cycles:180 Average:4612.24 Median:5230.95 stddev:1331.05 (25.4%) stddev-sans-first:1295.34 10:15:38 INFO - PROCESS | 2290 | Values: 326.7 330.2 333.1 343.1 346.5 5204.8 5228.6 5233.0 5237.7 5248.6 4968.8 4978.4 5086.5 5225.7 5230.2 4109.5 4135.5 4143.2 4144.9 4179.6 4122.5 4150.4 4162.9 4170.9 4181.2 3815.3 3875.7 4015.8 4117.6 4154.8 4080.4 4130.0 4139.8 4144.9 4146.4 3657.0 3658.9 3662.1 3665.5 3681.2 4983.5 4997.9 5007.5 5036.7 5055.9 4633.7 4653.3 4664.6 4691.7 4693.0 5396.4 5408.7 5416.0 5422.9 5430.2 3942.6 3997.4 4006.2 4013.5 4036.2 5571.4 5592.3 5603.7 5616.2 5621.2 4119.1 4130.7 4148.7 4158.5 4165.8 4013.7 4177.3 4177.5 4191.3 4210.3 4625.2 4671.2 4705.9 4708.9 4717.7 5407.8 5409.8 5414.9 5513.9 5524.6 1371.4 1397.1 1418.7 1433.8 1445.3 984.8 1013.7 1020.7 1021.8 1034.5 5178.5 5182.2 5231.7 5306.7 5310.3 5455.2 5514.5 5586.9 5604.9 5623.4 5030.6 5167.6 5245.5 5285.9 5289.4 2918.5 2920.1 2948.5 2951.9 2968.4 5602.3 5619.9 5653.7 5679.5 5731.5 5386.0 5619.6 5625.0 5630.2 5668.6 5690.8 5701.5 5708.2 5713.2 5720.0 5290.6 5546.6 5548.6 5576.9 5608.2 5548.4 5614.8 5616.3 5619.3 5638.7 5794.7 5821.4 5826.8 5852.9 5862.4 3863.5 3929.4 3936.3 4039.0 4060.3 5611.2 5619.9 5629.2 5644.2 5659.6 5279.0 5373.5 5449.8 5472.5 5552.0 5456.9 5502.5 5505.3 5533.3 5567.9 5181.7 5356.8 5403.4 5425.4 5470.0 5436.6 5508.7 5524.4 5540.3 5549.5 5434.6 5445.6 5446.6 5476.4 5485.5 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - PROCESS | 2290 | [#5] yui.html Cycles:180 Average:20470.21 Median:4655.77 stddev:28917.99 (621.1%) stddev-sans-first:28960.91 10:15:38 INFO - PROCESS | 2290 | Values: 674.3 695.7 713.2 778.3 783.9 15863.9 16330.3 16482.8 16576.8 16703.9 14985.3 15063.9 15169.5 15177.3 15202.0 684.8 800.3 817.8 828.2 833.9 830.8 835.5 837.2 838.1 838.2 867.4 867.5 868.0 868.9 869.1 715.8 826.5 861.4 864.7 867.9 628.9 629.5 630.1 631.0 631.5 14992.1 15038.5 15041.2 15055.1 15101.8 14274.9 14501.0 14666.8 14702.1 14929.1 12891.5 13078.1 13085.7 13118.8 13412.7 4401.8 4418.5 4595.5 4698.7 4701.0 73418.0 73573.0 74131.0 74148.6 75918.0 11297.3 11333.7 11684.1 11717.4 11737.6 20234.5 20369.9 20397.8 20527.7 20572.6 8407.4 8552.4 8758.8 8914.6 9007.4 4678.2 4695.6 4703.5 4739.9 4854.7 3379.5 3537.6 3547.8 3871.8 4023.6 664.4 666.2 668.4 675.4 679.9 707.1 732.0 739.8 741.0 752.5 9882.0 9933.3 10100.5 10176.9 10188.7 618.8 649.4 687.7 700.2 716.3 4493.8 4576.2 4639.0 4672.5 4700.3 73935.3 74185.0 74235.6 122890.4 126045.0 102016.5 102626.5 102675.5 102718.5 104167.5 88575.6 89300.0 89362.0 89746.0 91776.0 8875.9 8893.6 8926.4 8948.1 9139.0 49643.8 50148.7 50805.7 51607.5 55418.4 71535.3 71536.6 74830.0 75992.0 77081.0 12926.5 12932.8 13023.7 13510.9 13550.5 14456.2 14643.0 15176.2 15360.9 15407.5 13506.3 13747.2 14085.9 14086.5 14277.9 12758.8 12931.7 12936.5 13241.6 13306.6 13252.2 13389.7 13503.5 13587.0 14214.4 10956.8 11187.3 11282.9 11287.5 11599.4 11186.0 11727.4 11853.5 11924.2 12005.8 10:15:38 INFO - PROCESS | 2290 | -------- Summary: end -------- 10:15:38 INFO - PROCESS | 2290 | 10:15:38 INFO - TEST-INFO | 2290: exit 0 10:15:38 INFO - TEST-OK | dromaeo_css | took 1112907ms 10:15:38 INFO - TEST-START | kraken 10:15:38 INFO - operating with platform_type : linux_ 10:15:38 INFO - Initialising browser for kraken test... 10:15:38 INFO - TEST-INFO | started process 2573 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9qyByv/profile http://localhost:43115/getInfo.html) 10:15:42 INFO - PROCESS | 2573 | __metrics Screen width/height:1600/1200 10:15:42 INFO - PROCESS | 2573 | colorDepth:24 10:15:42 INFO - PROCESS | 2573 | Browser inner width/height: 1024/697 10:15:42 INFO - PROCESS | 2573 | __metrics 10:15:43 ERROR - PROCESS | 2573 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:15:44 INFO - PROCESS | 2573 | console.error: PushService: 10:15:44 INFO - PROCESS | 2573 | stateChangeProcessEnqueue: Error transitioning state 10:15:44 INFO - PROCESS | 2573 | UnknownError 10:15:44 INFO - TEST-INFO | 2573: exit 0 10:15:44 INFO - Browser initialized. 10:15:44 INFO - Running cycle 1/1 for kraken test... 10:15:44 INFO - TEST-INFO | started process 2654 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp9qyByv/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:15:56 INFO - PROCESS | 2654 | RSS: Main: 156786688 10:15:56 INFO - PROCESS | 2654 | 10:15:59 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:43115/tests/kraken/driver.html?testName=audio-beat-detection) 10:16:00 INFO - PROCESS | 2654 | RSS: Main: 214626304 10:16:00 INFO - PROCESS | 2654 | 10:16:02 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:43115/tests/kraken/driver.html?testName=audio-dft) 10:16:03 INFO - PROCESS | 2654 | RSS: Main: 201347072 10:16:03 INFO - PROCESS | 2654 | 10:16:06 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:43115/tests/kraken/driver.html?testName=audio-fft) 10:16:07 INFO - PROCESS | 2654 | RSS: Main: 268886016 10:16:07 INFO - PROCESS | 2654 | 10:16:09 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:43115/tests/kraken/driver.html?testName=audio-oscillator) 10:16:09 INFO - PROCESS | 2654 | RSS: Main: 179187712 10:16:09 INFO - PROCESS | 2654 | 10:16:11 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:43115/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:16:12 INFO - PROCESS | 2654 | RSS: Main: 183746560 10:16:12 INFO - PROCESS | 2654 | 10:16:15 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:43115/tests/kraken/driver.html?testName=imaging-darkroom) 10:16:16 INFO - PROCESS | 2654 | RSS: Main: 218243072 10:16:16 INFO - PROCESS | 2654 | 10:16:19 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:43115/tests/kraken/driver.html?testName=imaging-desaturate) 10:16:20 INFO - PROCESS | 2654 | RSS: Main: 219881472 10:16:20 INFO - PROCESS | 2654 | 10:16:22 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:43115/tests/kraken/driver.html?testName=json-parse-financial) 10:16:23 INFO - PROCESS | 2654 | RSS: Main: 220217344 10:16:23 INFO - PROCESS | 2654 | 10:16:24 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:43115/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:16:25 INFO - PROCESS | 2654 | RSS: Main: 206929920 10:16:25 INFO - PROCESS | 2654 | 10:16:27 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:16:27 INFO - PROCESS | 2654 | RSS: Main: 210587648 10:16:27 INFO - PROCESS | 2654 | 10:16:30 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:16:30 INFO - PROCESS | 2654 | RSS: Main: 216281088 10:16:30 INFO - PROCESS | 2654 | 10:16:33 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:16:33 INFO - PROCESS | 2654 | RSS: Main: 223555584 10:16:33 INFO - PROCESS | 2654 | 10:16:36 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:16:37 INFO - PROCESS | 2654 | RSS: Main: 222584832 10:16:37 INFO - PROCESS | 2654 | 10:16:38 INFO - PROCESS | 2654 | Cycle 1(1): loaded http://localhost:43115/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:43115/tests/kraken/driver.html?testName=ai-astar) 10:16:39 INFO - PROCESS | 2654 | RSS: Main: 264474624 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | __start_tp_report 10:16:39 INFO - PROCESS | 2654 | _x_x_mozilla_page_load 10:16:39 INFO - PROCESS | 2654 | _x_x_mozilla_page_load_details 10:16:39 INFO - PROCESS | 2654 | |i|pagename|runs| 10:16:39 INFO - PROCESS | 2654 | |0;ai-astar;111;107;111;112;110;111;110;111;110;109 10:16:39 INFO - PROCESS | 2654 | |1;audio-beat-detection;156;133;131;163;129;148;152;126;198;127 10:16:39 INFO - PROCESS | 2654 | |2;audio-dft;160;160;160;160;161;160;161;160;161;160 10:16:39 INFO - PROCESS | 2654 | |3;audio-fft;76;75;76;123;75;76;75;74;103;75 10:16:39 INFO - PROCESS | 2654 | |4;audio-oscillator;84;85;114;85;84;85;130;86;85;131 10:16:39 INFO - PROCESS | 2654 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;116;117 10:16:39 INFO - PROCESS | 2654 | |6;imaging-darkroom;145;145;145;150;146;146;145;146;146;146 10:16:39 INFO - PROCESS | 2654 | |7;imaging-desaturate;95;95;95;94;95;95;94;95;95;95 10:16:39 INFO - PROCESS | 2654 | |8;json-parse-financial;55;53;52;55;54;53;55;55;53;53 10:16:39 INFO - PROCESS | 2654 | |9;json-stringify-tinderbox;62;62;63;62;63;63;62;64;63;62 10:16:39 INFO - PROCESS | 2654 | |10;stanford-crypto-aes;80;79;79;84;85;83;81;80;112;81 10:16:39 INFO - PROCESS | 2654 | |11;stanford-crypto-ccm;123;124;120;132;120;133;119;120;128;129 10:16:39 INFO - PROCESS | 2654 | |12;stanford-crypto-pbkdf2;160;159;160;161;164;161;158;159;158;158 10:16:39 INFO - PROCESS | 2654 | |13;stanford-crypto-sha256-iterative;64;63;67;62;66;67;62;62;63;64 10:16:39 INFO - PROCESS | 2654 | __end_tp_report 10:16:39 INFO - PROCESS | 2654 | __start_cc_report 10:16:39 INFO - PROCESS | 2654 | _x_x_mozilla_cycle_collect,1630 10:16:39 INFO - PROCESS | 2654 | __end_cc_report 10:16:39 INFO - PROCESS | 2654 | __startTimestamp1465319799094__endTimestamp 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | ------- Summary: start ------- 10:16:39 INFO - PROCESS | 2654 | Number of tests: 14 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#0] ai-astar Cycles:10 Average:110.20 Median:111.00 stddev:1.40 (1.3%) stddev-sans-first:1.45 10:16:39 INFO - PROCESS | 2654 | Values: 111.0 107.0 111.0 112.0 110.0 111.0 110.0 111.0 110.0 109.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#1] audio-beat-detection Cycles:10 Average:146.30 Median:150.00 stddev:22.55 (15.0%) stddev-sans-first:23.64 10:16:39 INFO - PROCESS | 2654 | Values: 156.0 133.0 131.0 163.0 129.0 148.0 152.0 126.0 198.0 127.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#2] audio-dft Cycles:10 Average:160.30 Median:160.00 stddev:0.48 (0.3%) stddev-sans-first:0.50 10:16:39 INFO - PROCESS | 2654 | Values: 160.0 160.0 160.0 160.0 161.0 160.0 161.0 160.0 161.0 160.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#3] audio-fft Cycles:10 Average:82.80 Median:75.00 stddev:16.61 (22.1%) stddev-sans-first:17.44 10:16:39 INFO - PROCESS | 2654 | Values: 76.0 75.0 76.0 123.0 75.0 76.0 75.0 74.0 103.0 75.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#4] audio-oscillator Cycles:10 Average:96.90 Median:85.00 stddev:19.91 (23.4%) stddev-sans-first:20.57 10:16:39 INFO - PROCESS | 2654 | Values: 84.0 85.0 114.0 85.0 84.0 85.0 130.0 86.0 85.0 131.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#5] imaging-gaussian-blur Cycles:10 Average:116.90 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 10:16:39 INFO - PROCESS | 2654 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 116.0 117.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#6] imaging-darkroom Cycles:10 Average:146.00 Median:146.00 stddev:1.49 (1.0%) stddev-sans-first:1.54 10:16:39 INFO - PROCESS | 2654 | Values: 145.0 145.0 145.0 150.0 146.0 146.0 145.0 146.0 146.0 146.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 10:16:39 INFO - PROCESS | 2654 | Values: 95.0 95.0 95.0 94.0 95.0 95.0 94.0 95.0 95.0 95.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:54.50 stddev:1.14 (2.1%) stddev-sans-first:1.12 10:16:39 INFO - PROCESS | 2654 | Values: 55.0 53.0 52.0 55.0 54.0 53.0 55.0 55.0 53.0 53.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#9] json-stringify-tinderbox Cycles:10 Average:62.60 Median:63.00 stddev:0.70 (1.1%) stddev-sans-first:0.71 10:16:39 INFO - PROCESS | 2654 | Values: 62.0 62.0 63.0 62.0 63.0 63.0 62.0 64.0 63.0 62.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#10] stanford-crypto-aes Cycles:10 Average:84.40 Median:81.00 stddev:9.91 (12.2%) stddev-sans-first:10.39 10:16:39 INFO - PROCESS | 2654 | Values: 80.0 79.0 79.0 84.0 85.0 83.0 81.0 80.0 112.0 81.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#11] stanford-crypto-ccm Cycles:10 Average:124.80 Median:126.00 stddev:5.31 (4.2%) stddev-sans-first:5.59 10:16:39 INFO - PROCESS | 2654 | Values: 123.0 124.0 120.0 132.0 120.0 133.0 119.0 120.0 128.0 129.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:159.80 Median:160.00 stddev:1.87 (1.2%) stddev-sans-first:1.99 10:16:39 INFO - PROCESS | 2654 | Values: 160.0 159.0 160.0 161.0 164.0 161.0 158.0 159.0 158.0 158.0 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - PROCESS | 2654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.00 Median:64.00 stddev:2.00 (3.1%) stddev-sans-first:2.12 10:16:39 INFO - PROCESS | 2654 | Values: 64.0 63.0 67.0 62.0 66.0 67.0 62.0 62.0 63.0 64.0 10:16:39 INFO - PROCESS | 2654 | -------- Summary: end -------- 10:16:39 INFO - PROCESS | 2654 | 10:16:39 INFO - TEST-INFO | 2654: exit 0 10:16:40 INFO - TEST-OK | kraken | took 61116ms 10:16:40 INFO - SUITE-END | took 1174s 10:16:40 INFO - Completed test suite (00:19:34) 10:16:40 INFO - javascript benchmark 10:16:40 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3107.3940581586585, 3112.1597168764433, 3133.1070644866204, 3164.968350316496, 3191.8084914905107, 5176.741162941857, 5252.054630166568, 5314.415414304428, 5386.326709161355, 5412.91880621791, 5298.523132918037, 5306.177542480912, 5322.2282768998475, 5385.246065550826, 5386.2997810284605, 4832.468428472856, 4875.488073752258, 4882.829100981467, 4890.315355850184, 5017.397912250535, 4969.652124351297, 4980.128477516439, 5013.874653133665, 5025.522575355354, 5034.169362055257, 4748.786304616283, 4786.736729479877, 4835.830745923893, 4871.391076115486, 4909.3126962225315, 4910.435299940516, 4925.482725089799, 4926.3595732554895, 4926.655134140611, 4960.131976904064, 4684.180268453007, 4887.437944636376, 4909.631777616693, 4910.484399138075, 4923.606111511071, 5239.73801309933, 5303.7082960437165, 5316.548093412064, 5407.810726624549, 5410.458954104597, 5805.593608447411, 5813.47678708914, 5823.621464604787, 5823.82528524145, 5826.650400975913, 5538.6399884007415, 5619.6066275360745, 5806.129080637896, 5844.152597873284, 5852.151438041503, 5313.335833020872, 5368.516833485005, 5414.593905457106, 5418.647787893774, 5421.5391691706645, 5904.380040095814, 5907.881842363215, 5913.231279933667, 5913.940860591425, 5924.14099955504, 5436.347638283352, 5572.832815015556, 5641.774329026793, 5692.0, 5693.259876216065, 5789.421057894261, 5792.565557583198, 5833.416658334218, 5842.123681447817, 5848.912266316009, 5703.315602127771, 5739.2252045973255, 5747.08046712524, 5754.568407369464, 5759.596828222068, 5440.455954404607, 5585.357683866408, 5605.159226116034, 5654.491095801315, 5764.884702305931, 4214.789260536961, 4215.936760948527, 4216.2832318505925, 4217.34631132175, 4250.3199488081755, 3800.790956497364, 3804.1440675848266, 3806.0484878780303, 3813.389857622767, 3834.8082595870096, 5243.344581927259, 5304.973475132677, 5333.306670132858, 5366.892662146735, 5370.892582148335, 5737.5983681141915, 5738.024535828919, 5741.62679425836, 5747.3103227612955, 5774.68239246837, 5363.061464243741, 5406.270153529302, 5412.269343638637, 5413.864653383697, 5417.3770016447825, 4825.1555977703765, 4852.660313778072, 4888.462269150391, 4915.65590408668, 4971.502849715, 5861.3552509223955, 5863.853403664943, 5865.061590145641, 5873.676947767828, 5874.853128671817, 5706.2011318414625, 5733.111367738007, 5733.226014487991, 5736.71316434185, 5737.168110624019, 5756.194132821324, 5827.4172582741385, 5875.559333049953, 5879.235699359056, 5914.349421563538, 5642.492175704207, 5646.520045795989, 5692.260006199168, 5692.658440493584, 5697.686627235542, 5603.971980140073, 5771.422857714195, 5783.19035335045, 5785.39253378406, 5789.189513468202, 5500.669959802424, 5567.359753628222, 5602.2717046783655, 5640.041192997282, 5647.068233741551, 5623.465770751772, 5632.324121105494, 5645.294338207724, 5648.068068768607, 5652.7173641318595, 5298.8940221196135, 5423.484768946943, 5512.6968016759465, 5515.062439385394, 5526.088195447706, 5670.943290567042, 5760.740766665594, 5765.5675824314185, 5767.077267637158, 5771.769129234784, 5587.776488940397, 5587.944120558905, 5591.0, 5605.04714198579, 5629.352624448241], "value": 5278.566450845857, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [596.296370283066, 628.2021832273012, 628.6762317406534, 635.8092572228331, 648.7988723495716, 32526.349473010527, 34778.65221347786, 34836.0, 34860.30279394411, 34863.302733945304, 47926.52073479264, 49491.50508494923, 49527.50472495274, 49562.25656615154, 49569.25646115302, 8705.912940870605, 8915.465072095694, 8916.375853690211, 8920.776980575472, 8921.01868794432, 8779.824403511926, 8906.242969347613, 8907.821843563124, 8908.0, 8908.8663670045, 8825.382223244376, 8861.335399845006, 8873.73378798637, 8880.555972201364, 8912.688055918044, 8766.736997890073, 8865.734027979172, 8866.822663546758, 8872.334574906909, 8877.911220887772, 8669.306455483546, 8709.7822554436, 8718.651253949834, 8752.956235218848, 8761.956190219073, 41864.1627167458, 42148.78925605383, 42198.78900605477, 42347.36478952818, 42384.36423453651, 3376.4428869236544, 3461.238527523941, 3649.2883887641656, 3992.40113982902, 3998.9400158997637, 32529.837350813094, 32777.01668949935, 32867.17832054194, 33031.3393732124, 33525.32949341, 4240.109576988836, 4351.412559304516, 4383.583559561836, 4480.283154695233, 4519.525449827786, 341162.0, 341939.0, 343387.9999999975, 346319.0, 346448.0, 51475.485245147815, 51817.0, 52487.212691809655, 52545.0, 52581.2112818308, 62007.379926201065, 62098.0, 62141.0, 62210.37789622136, 62213.68893155596, 17042.403515876886, 17312.307507699803, 17346.65306693859, 17357.73963390525, 17359.30562777475, 12645.93677031609, 12753.426095825711, 12756.744865102832, 12761.68095797594, 12761.808572871414, 14899.255037248311, 16526.173691315387, 16798.580035498966, 16807.831921680627, 16858.0, 5645.717714114277, 6617.305182955816, 6771.52599318043, 6881.483888708367, 7051.753188638476, 1049.7612816876008, 1072.7747173093737, 1096.52849274811, 1125.4597793059213, 1177.8645455772526, 31083.667244782173, 31578.0, 31753.523697144563, 32338.35323293521, 32461.0, 1113.1282484892258, 1161.9709507262387, 1192.4881224792903, 1205.4816428935642, 1258.974820503603, 17146.485605431855, 17319.480415587554, 17339.65320693579, 17340.219690113976, 17372.47882563525, 504372.0, 507031.0, 509912.0, 510888.0, 513474.0, 59244.40755592475, 59257.40742592605, 59271.11093333604, 59380.406195938354, 59423.702881486184, 1292.5476083370745, 1298.7597294500547, 1349.6221058103747, 1405.4588983241526, 1426.415169780385, 32192.517112243804, 33897.322053559285, 33972.49041264432, 34271.657283426845, 34601.307973840885, 53594.73202633962, 55215.0, 55278.72360638171, 61134.0, 61262.69368653128, 438170.0, 446707.0, 447196.0, 448509.0, 448659.0, 24718.382040448414, 26846.194614161606, 27465.862670686518, 27585.310367240978, 29741.0, 47695.28457073077, 48140.518594815, 48168.5183148164, 49201.75399123125, 49235.753821232094, 41478.792606036775, 41554.37668435036, 42137.1572568553, 42855.0, 42919.57080429156, 40733.59266407298, 40828.79585602053, 41017.31062455805, 41835.0, 42216.36675449809, 40630.59369406268, 41652.58347416487, 41856.0, 41882.37176442417, 41904.58095419007, 35153.64846351504, 36708.632913670524, 36843.63156368509, 37020.0, 37256.81371593125, 27933.0, 28078.2980425491, 28086.0, 28199.577006344512, 28303.0], "value": 20128.934356671423, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.6154714511916, 392.4055056589267, 393.307778310174, 393.5640699154994, 401.3297792686214, 17337.913310433447, 18745.15646795894, 18775.90612046938, 18810.62378752426, 20086.196552137895, 19058.809411905877, 19135.0, 19351.70972435411, 19396.418107456764, 19604.411867643958, 5512.641678290898, 5573.777048918038, 5655.236543066695, 5668.43315668434, 5687.630304030225, 5503.9174412383645, 5596.272484576999, 5603.80386686466, 5606.6636001839815, 5611.9438805612035, 5456.0, 5552.194931734896, 5579.69311687857, 5720.027595308787, 5720.685362305072, 5719.59962802604, 5754.992876246661, 5759.999999999958, 5762.855928601818, 5782.45066718661, 5376.327959005124, 5633.57748168889, 5638.210650508932, 5638.4361563843695, 5644.689542075184, 13018.218906865524, 13394.0, 13445.0, 13735.107218030797, 14151.292435378293, 2225.5215128747395, 2289.6371103747847, 2452.9877350613133, 2514.786243169333, 2774.0706863200853, 9901.504924753734, 10111.494425278706, 10352.896471035343, 13762.449502019907, 14071.788923166161, 1794.4168145352812, 1852.397970659541, 1883.5197024758736, 1891.7162425636127, 1895.1471837672964, 79920.0, 81763.0, 82567.0, 82797.58601207015, 83281.0, 10994.120470362512, 11106.555737770399, 11117.666470005914, 11144.764989304174, 11491.54033838637, 15203.543893683207, 15344.156071415957, 15348.0, 15384.461543846135, 15421.383144674312, 8011.319037881729, 8255.752327430188, 8287.62705678246, 8477.788055298544, 8481.406301558955, 7974.162712915177, 8017.4387792855105, 8097.595120244082, 8220.219079187467, 8287.50274983503, 4693.24908014716, 4719.244920812654, 4738.549837765407, 4817.710937343771, 4850.223964165716, 1973.190991693399, 2003.4590660521578, 2003.9799602003793, 2015.61703276377, 2049.108637742587, 963.604921981984, 966.3139171188394, 975.2588033094898, 1000.2598077422655, 1020.0921180149672, 18807.529811754815, 19132.61734765297, 19280.132394042022, 19344.516387090436, 19450.60433523113, 964.5900492290748, 975.2588033094898, 1000.2448151645461, 1000.849872519128, 1008.7679833638305, 10154.695359139238, 10852.565897364017, 10880.67357979262, 10917.39954302521, 10949.83575246372, 476766.0, 478906.0, 479697.0, 484473.0, 487797.0, 57638.0, 76691.0, 101315.49342253241, 101681.49159254157, 103737.0, 998.4658207858906, 1033.540074666781, 1049.6431213387332, 1069.4920162570893, 1074.2641290715885, 17415.738763918296, 17465.126743662513, 17900.462986110437, 18022.279108836025, 18267.90866045661, 42067.00363465466, 42090.57909420867, 42752.358714618684, 44022.339664905696, 44070.0, 58932.0, 59026.11460828177, 60722.0, 61060.0, 61500.69249653723, 8451.8732219018, 8620.870686939827, 8687.131286871263, 8832.116788321116, 9121.726348209564, 10769.369171256434, 13299.401526931118, 13528.120672156278, 13723.656908577366, 13740.10689305192, 11289.379084150121, 11639.5926142588, 11736.823947640964, 11953.462094205608, 11982.221155624857, 11332.773344532898, 11419.0, 11638.592649257575, 12019.75960480768, 12169.756604868031, 11237.64009558002, 11337.093032557541, 11590.768184636092, 11670.649880503599, 11720.472578734149, 8243.216894395024, 8301.750947471586, 8314.168583141638, 8402.41183117176, 8419.452735572168, 10495.160387168855, 11238.10095192399, 11279.492422840827, 11340.489677964677, 11488.770224595293], "value": 9121.503112779656, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1380.6548362909273, 1507.3669058995224, 1526.6565022869854, 1656.3291866793945, 1829.6889528780114, 8195.22145396188, 8409.201125893038, 8429.199226073528, 8871.37900346976, 8982.86525702115, 8574.785630359243, 8793.648254069829, 8794.736157915257, 8799.076097009818, 8809.427387219826, 2016.526116362661, 2144.4424449643047, 2193.462601662595, 2277.9202727904485, 2319.118734880743, 2265.456290490287, 2303.608386574286, 2306.3888069661552, 2307.4462129088984, 2309.4341886237894, 2275.772422757719, 2276.237460450751, 2282.611955967481, 2296.1619009061656, 2298.1267118494948, 1845.6031953130075, 2169.3383518026994, 2184.9890750546306, 2231.3640612425543, 2252.245497758253, 2269.251147121446, 2281.6691579721014, 2289.553537060274, 2295.426143464134, 2295.4490922178725, 8763.12368763125, 8804.559772011438, 8818.955905220497, 8820.514871682055, 8824.735257942271, 4972.129877271499, 5096.745162741848, 5105.132127538327, 5105.157648987913, 5106.872328191787, 3047.3752880659413, 3164.3196712706876, 3203.4073696366013, 3207.8556464959133, 3322.8172450515212, 1034.984475232872, 1036.046836910037, 1036.556417154612, 1039.8960103989616, 1062.6015244283394, 12163.148579599432, 12373.319467429288, 12379.876201238052, 12390.504379824797, 12408.56570020045, 4372.3878656988045, 4406.691531592822, 4434.312681534368, 4464.888377790581, 4566.680332376702, 10379.84430233547, 10414.218933580012, 10419.166466682647, 10424.478776061167, 10427.530761115768, 5252.44849290819, 5257.737113144404, 5259.368875734936, 5261.0, 5261.447548007481, 4155.3143731284, 4672.602828759594, 4787.3537072495465, 4808.230683090689, 4872.756362181876, 3879.728419010642, 3920.862769803043, 3997.420374045747, 4024.7182697210915, 4040.818163182605, 1199.6161228407, 1203.2359451748202, 1211.1461419699092, 1213.6177104212145, 1213.787587172259, 853.2193043365252, 862.9137086291446, 883.6642076011075, 888.6223355074068, 966.9371490853072, 5841.561882858718, 5889.587728858939, 5896.793612223637, 6105.267367915879, 6114.7248373822395, 990.321629683669, 1003.3979612232748, 1013.5388398278766, 1038.5378506564657, 1117.9776404471866, 4085.9795701021303, 4143.96400899775, 4198.790060496963, 4253.085586598896, 4261.74429534229, 9901.108900199017, 10007.99920008008, 10069.647562335284, 10132.138768204784, 10147.188224941987, 10003.34978226413, 10029.899701002896, 10084.747881303027, 10091.848622270672, 10096.899031009743, 7978.122406535276, 8042.678292868337, 8102.351811855037, 8107.108218096005, 8127.1466496017065, 6066.848328791815, 6076.1493390924425, 6133.3866613338305, 6199.411055949678, 6219.782307619212, 9841.360311579725, 10169.338992965433, 10173.389596624227, 10182.0, 10183.694489165337, 10080.04239597237, 10231.13035392, 10521.316114452537, 10542.420166890895, 10614.203934704774, 6937.965310173417, 7106.324899134574, 7108.644567771694, 7464.477486576105, 7653.043369578802, 7970.960145199237, 8154.959225203836, 8406.915930840858, 8416.452930559493, 8678.913210867811, 7150.5352152109945, 7271.272872712686, 7341.926580734124, 7354.77935661931, 7434.73978410753, 7319.0, 7418.703251869865, 7476.813079672834, 7593.468457207942, 7646.120696120018, 7087.751928682472, 7091.574505529685, 7126.0, 7138.179109402485, 7152.642367881689, 4921.704697718148, 4938.827141050046, 5016.698998060128, 5075.619328550448, 5093.516115968977, 5057.241413787961, 5097.566706829961, 5147.459516750837, 5173.1205695031185, 5229.241759944711], "value": 4550.858969502739, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.74840372912854, 330.2009137886315, 333.0939843625339, 343.1250311705151, 346.45260488428283, 5204.817831375903, 5228.562145410009, 5232.979568984045, 5237.7381130943395, 5248.6063545234065, 4968.826091086813, 4978.352814134158, 5086.491350864921, 5225.667454799031, 5230.163173892178, 4109.486314210723, 4135.462389889325, 4143.19207754488, 4144.8549300774475, 4179.623833854953, 4122.515894278057, 4150.418941348214, 4162.895927601804, 4170.916581668364, 4181.205570941512, 3815.2560250751117, 3875.7286989910717, 4015.7791321477307, 4117.588241175873, 4154.8130334134885, 4080.3879418087226, 4129.958700413017, 4139.834406623747, 4144.854930077463, 4146.3780432935, 3656.981715091434, 3658.8536458541626, 3662.1393972416463, 3665.541807274091, 3681.245344704329, 4983.526564976321, 4997.90004199914, 5007.474215207387, 5036.748162591855, 5055.949440505621, 4633.721976681376, 4653.325267836145, 4664.626829853603, 4691.671582989192, 4693.0, 5396.43337449566, 5408.702521361323, 5416.0, 5422.864428389283, 5430.239766432703, 3942.56631770505, 3997.440998010778, 4006.1587066716024, 4013.5350597032166, 4036.2129384769983, 5571.387147413781, 5592.3009623797025, 5603.691796951166, 5616.157576363537, 5621.241132447149, 4119.073208528117, 4130.690198235145, 4148.688848336386, 4158.500979882373, 4165.75005499671, 4013.7190396671954, 4177.3316269396755, 4177.4778152730905, 4191.3293872980785, 4210.263203939359, 4625.167469855458, 4671.159191345522, 4705.858824235278, 4708.88227794301, 4717.669763116549, 5407.783688652488, 5409.756560954766, 5414.945850541523, 5513.889722205534, 5524.596800391842, 1371.4445649511765, 1397.1407584335764, 1418.7446259673359, 1433.7634290341978, 1445.3134760988446, 984.8178087053918, 1013.7364285285879, 1020.6886899495577, 1021.8402113601117, 1034.462079718542, 5178.456262092502, 5182.24857395683, 5231.738413079331, 5306.734663266821, 5310.2831117798605, 5455.236266922634, 5514.476124768141, 5586.888262234733, 5604.915926261109, 5623.441334192932, 5030.647854650139, 5167.560757335668, 5245.527902488794, 5285.867853303621, 5289.444608316148, 2918.5038543447663, 2920.0801747449486, 2948.4987552115763, 2951.9409611808073, 2968.391479746647, 5602.271704678447, 5619.904118696897, 5653.689047102366, 5679.517241034476, 5731.455511726379, 5386.003589335985, 5619.634723742944, 5625.0, 5630.155476678531, 5668.603197776199, 5690.800821971211, 5701.543876489871, 5708.229389032427, 5713.22871412354, 5720.0, 5290.629655924048, 5546.584006199632, 5548.556115510669, 5576.91634625489, 5608.158776183605, 5548.389677135593, 5614.848955964099, 5616.269884915098, 5619.269494965628, 5638.731285460739, 5794.739236734273, 5821.359650438533, 5826.82519524415, 5852.887951289242, 5862.443067908542, 3863.517060367454, 3929.4498770171626, 3936.3111455495746, 4038.959610403976, 4060.309747343018, 5611.158326250932, 5619.943800561942, 5629.183768353484, 5644.153376993484, 5659.575531835211, 5279.023380674587, 5373.489518495737, 5449.782008719765, 5472.480114389126, 5552.0, 5456.863578410572, 5502.477264659851, 5505.2933590587045, 5533.280673512418, 5567.860803479945, 5181.715005674724, 5356.812511562077, 5403.405625381283, 5425.376081750649, 5469.972650136724, 5436.6194366393975, 5508.697021663686, 5524.364698059614, 5540.279763630702, 5549.500544950813, 5434.592405569519, 5445.564354851523, 5446.645968012067, 5476.397596264487, 5485.50630443262], "value": 4195.114610447107, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [674.2616834566148, 695.7147569496505, 713.1513498936263, 778.2956424435886, 783.9176886426925, 15863.920680396597, 16330.265138068786, 16482.83517164828, 16576.834231657678, 16703.91648041758, 14985.250737463139, 15063.924680376611, 15169.54491365258, 15177.317020734066, 15202.0, 684.7534887440518, 800.2717527050386, 817.8200795824924, 828.2214718164936, 833.8832563441123, 830.8296799156159, 835.4861760017607, 837.1837458477966, 838.0864857305536, 838.2246422059601, 867.3581549653275, 867.5011868175793, 868.0278088540844, 868.9391742578022, 869.0527325215514, 715.8496715689712, 826.5247482697444, 861.4314552395451, 864.7319331007376, 867.8784970104191, 628.9088082228097, 629.496402877696, 630.0517720830121, 631.0124654915044, 631.4821846086211, 14992.100473971597, 15038.473653422077, 15041.247937603077, 15055.096694198382, 15101.773473397909, 14274.857251427457, 14501.0, 14666.779998300033, 14702.117872927658, 14929.104253744701, 12891.548795792196, 13078.149920255248, 13085.738285234242, 13118.803217951738, 13412.664683382896, 4401.757903315316, 4418.536053714346, 4595.540445955411, 4698.718076915362, 4701.012787314634, 73418.0, 73573.0, 74131.0, 74148.6292568539, 75917.99999999945, 11297.26567773092, 11333.716657083474, 11684.065274777997, 11717.355545445082, 11737.589184378676, 20234.494137646678, 20369.89815050945, 20397.79602203959, 20527.692084618742, 20572.58854822925, 8407.369447291478, 8552.358573107042, 8758.781030474289, 8914.598843051948, 9007.414518056305, 4678.204705200124, 4695.577398034194, 4703.482616912137, 4739.905201895943, 4854.660173787801, 3379.4930760386137, 3537.5931767846514, 3547.7693949893173, 3871.7676939383164, 4023.637872591481, 664.4352300544499, 666.1573110015834, 668.4117976180929, 675.4393853101914, 679.8776220280397, 707.1302298173173, 731.9634018299064, 739.7521830186947, 740.9629518524052, 752.450710980976, 9882.0, 9933.304668673123, 10100.494975251208, 10176.898231017743, 10188.694339169837, 618.7927044440073, 649.389573800626, 687.6500823592459, 700.1528150937423, 716.3266529462289, 4493.820247190076, 4576.222042252825, 4639.0, 4672.5327467253, 4700.341952126705, 73935.26064739283, 74185.0, 74235.62882185554, 122890.38554807348, 126045.0, 102016.48991754993, 102626.48686756518, 102675.48662256642, 102718.48640756898, 104167.4791626037, 88575.55712221526, 89300.0, 89362.0, 89746.0, 91776.0, 8875.911240887508, 8893.599788009684, 8926.419782714103, 8948.149925757043, 9139.040400758091, 49643.75178124086, 50148.74925625348, 50805.745971269906, 51607.48392516177, 55418.44581554133, 71535.28464715286, 71536.64231678809, 74830.0, 75992.0, 77081.0, 12926.482940682268, 12932.806007909701, 13023.739525209254, 13510.86489135096, 13550.525731599346, 14456.20490873012, 14643.0, 15176.241187940337, 15360.923195383952, 15407.537773866801, 13506.257155856521, 13747.175169489863, 14085.859141408864, 14086.506972255922, 14277.9291553136, 12758.80974928981, 12931.741365172831, 12936.547220847226, 13241.602751917459, 13306.600801975956, 13252.20486770797, 13389.732205356033, 13503.514613392716, 13587.0, 14214.431422742975, 10956.830843220014, 11187.272827266203, 11282.887171128512, 11287.492062857355, 11599.420028998686, 11186.0, 11727.35499547533, 11853.52585896589, 11924.224925379822, 12005.75988480208], "value": 7622.235798492155, "unit": "score"}], "name": "dromaeo_css", "value": 7214.864267217456, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 107.0, 111.0, 112.0, 110.0, 111.0, 110.0, 111.0, 110.0, 109.0], "value": 110.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 133.0, 131.0, 163.0, 129.0, 148.0, 152.0, 126.0, 198.0, 127.0], "value": 146.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 161.0, 160.0, 161.0, 160.0, 161.0, 160.0], "value": 160.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 76.0, 123.0, 75.0, 76.0, 75.0, 74.0, 103.0, 75.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 114.0, 85.0, 84.0, 85.0, 130.0, 86.0, 85.0, 131.0], "value": 96.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 117.0, 116.0, 117.0], "value": 116.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 145.0, 145.0, 150.0, 146.0, 146.0, 145.0, 146.0, 146.0, 146.0], "value": 146.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 52.0, 55.0, 54.0, 53.0, 55.0, 55.0, 53.0, 53.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 64.0, 63.0, 62.0], "value": 62.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 79.0, 79.0, 84.0, 85.0, 83.0, 81.0, 80.0, 112.0, 81.0], "value": 84.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 124.0, 120.0, 132.0, 120.0, 133.0, 119.0, 120.0, 128.0, 129.0], "value": 124.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 159.0, 160.0, 161.0, 164.0, 161.0, 158.0, 159.0, 158.0, 158.0], "value": 159.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 67.0, 62.0, 66.0, 67.0, 62.0, 62.0, 63.0, 64.0], "value": 64.0, "unit": "score"}], "name": "kraken", "value": 1503.6, "alertThreshold": 2.0}]} 10:16:40 INFO - Return code: 0 10:16:40 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:16:40 ERROR - # TBPL SUCCESS # 10:16:40 INFO - Running post-action listener: _resource_record_post_action 10:16:40 INFO - ##### 10:16:40 INFO - ##### Finished run-tests step (success) 10:16:40 INFO - ##### 10:16:40 INFO - Running post-run listener: _resource_record_post_run 10:16:40 INFO - Total resource usage - Wall time: 1192s; CPU: 13.0%; Read bytes: 12288; Write bytes: 541310976; Read time: 280; Write time: 532676 10:16:40 INFO - TinderboxPrint: CPU usage
13.1% 10:16:40 INFO - TinderboxPrint: I/O read bytes / time
12,288 / 280 10:16:40 INFO - TinderboxPrint: I/O write bytes / time
541,310,976 / 532,676 10:16:40 INFO - TinderboxPrint: CPU idle
8,262.5 (86.8%) 10:16:40 INFO - TinderboxPrint: CPU user
1,206.9 (12.7%) 10:16:40 INFO - TinderboxPrint: Swap in / out
0 / 0 10:16:40 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 247988224; Read time: 0; Write time: 261128 10:16:40 INFO - run-tests - Wall time: 1176s; CPU: 13.0%; Read bytes: 12288; Write bytes: 293322752; Read time: 280; Write time: 271548 10:16:41 INFO - Running post-run listener: _upload_blobber_files 10:16:41 INFO - Blob upload gear active. 10:16:41 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:16:41 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 10:16:41 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-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:16:41 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-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:16:41 INFO - (blobuploader) - INFO - Open directory for files ... 10:16:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:16:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:42 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:16:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:42 INFO - (blobuploader) - INFO - Done attempting. 10:16:42 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:16:42 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:42 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:45 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:16:45 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:45 INFO - (blobuploader) - INFO - Done attempting. 10:16:45 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:16:45 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:16:45 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:16:46 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:16:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:16:46 INFO - (blobuploader) - INFO - Done attempting. 10:16:46 INFO - (blobuploader) - INFO - Iteration through files over. 10:16:46 INFO - Return code: 0 10:16:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:16:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:16:46 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7d1262314d579a1043f246ae40e0eeb456167bdd31610f8ce1ecce0caa8d1ae6266be1975e536e879713c7be4fc4d34c1a06508fabded3d6912134c0596e109", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3cfcf67ede895e08c0ab8109f7a5d7129140b4c9323fd4e00b1c3aec1a5c0ade09c26723fb7ccc404202f9b1479bb6186d659d3b940cbdaf123cbb7abb4a5fa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb1499234166abfea1aac90c8c2a3c1dc0a3ed65dffbaec7528a2c5b8b5e8b55dbc8621ce1e8092da5b728c1a2c2c79ae997b468e5dde66c7862c6ea5ac23aa1"} 10:16:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:16:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:16:46 INFO - Contents: 10:16:46 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7d1262314d579a1043f246ae40e0eeb456167bdd31610f8ce1ecce0caa8d1ae6266be1975e536e879713c7be4fc4d34c1a06508fabded3d6912134c0596e109", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3cfcf67ede895e08c0ab8109f7a5d7129140b4c9323fd4e00b1c3aec1a5c0ade09c26723fb7ccc404202f9b1479bb6186d659d3b940cbdaf123cbb7abb4a5fa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb1499234166abfea1aac90c8c2a3c1dc0a3ed65dffbaec7528a2c5b8b5e8b55dbc8621ce1e8092da5b728c1a2c2c79ae997b468e5dde66c7862c6ea5ac23aa1"} 10:16:46 INFO - Running post-run listener: copy_logs_to_upload_dir 10:16:46 INFO - Copying logs to upload dir... 10:16:46 INFO - mkdir: /builds/slave/test/build/upload/logs 10:16:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1240.212826 ========= master_lag: 0.01 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 40 secs) (at 2016-06-07 10:16:45.990327) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 10:16:45.993773) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7d1262314d579a1043f246ae40e0eeb456167bdd31610f8ce1ecce0caa8d1ae6266be1975e536e879713c7be4fc4d34c1a06508fabded3d6912134c0596e109", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3cfcf67ede895e08c0ab8109f7a5d7129140b4c9323fd4e00b1c3aec1a5c0ade09c26723fb7ccc404202f9b1479bb6186d659d3b940cbdaf123cbb7abb4a5fa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb1499234166abfea1aac90c8c2a3c1dc0a3ed65dffbaec7528a2c5b8b5e8b55dbc8621ce1e8092da5b728c1a2c2c79ae997b468e5dde66c7862c6ea5ac23aa1"} build_url:https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011265 build_url: 'https://queue.taskcluster.net/v1/task/GZFqHKwhQfGpALr822c2MA/artifacts/public/build/firefox-50.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/d7d1262314d579a1043f246ae40e0eeb456167bdd31610f8ce1ecce0caa8d1ae6266be1975e536e879713c7be4fc4d34c1a06508fabded3d6912134c0596e109", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3cfcf67ede895e08c0ab8109f7a5d7129140b4c9323fd4e00b1c3aec1a5c0ade09c26723fb7ccc404202f9b1479bb6186d659d3b940cbdaf123cbb7abb4a5fa9", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/eb1499234166abfea1aac90c8c2a3c1dc0a3ed65dffbaec7528a2c5b8b5e8b55dbc8621ce1e8092da5b728c1a2c2c79ae997b468e5dde66c7862c6ea5ac23aa1"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-06-07 10:16:46.019846) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 10:16:46.020280) ========= 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=934fc3100db09dcb9af4d4b6000001ab-1465315354.477655-1134415003 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004458 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-06-07 10:16:46.105501) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 10:16:46.105865) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-06-07 10:16:46.106211) ========= ========= Total master_lag: 0.16 =========