builder: mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s-pgo slave: talos-linux64-ix-018 starttime: 1470094305.14 results: success (0) buildid: 20160801123750 builduid: 5a38664472d84844bb6ee371e0c4ddcd revision: 3998308ce6053f95dbb3bd814a72eaafb7b31fae ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.140307) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.141332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.142027) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.157295) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.157891) ========= 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=62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-08-01 16:31:45-- 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% 113M=0s 2016-08-01 16:31:45 (113 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.144434 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.317697) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.318056) ========= 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=62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017185 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.367161) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:45.367548) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3998308ce6053f95dbb3bd814a72eaafb7b31fae --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 3998308ce6053f95dbb3bd814a72eaafb7b31fae --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=62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-08-01 16:31:45,390 truncating revision to first 12 chars 2016-08-01 16:31:45,390 Setting DEBUG logging. 2016-08-01 16:31:45,390 attempt 1/10 2016-08-01 16:31:45,390 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/3998308ce605?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-01 16:31:45,712 unpacking tar archive at: mozilla-beta-3998308ce605/testing/mozharness/ program finished with exit code 0 elapsedTime=0.664476 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:46.046705) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:46.047085) ========= script_repo_revision: 3998308ce6053f95dbb3bd814a72eaafb7b31fae ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:46.047557) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:46.047880) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-01 16:31:46.057822) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-08-01 16:31:46.058144) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] 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=62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 16:31:46 INFO - MultiFileLogger online at 20160801 16:31:46 in /builds/slave/test 16:31:46 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 16:31:46 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:31:46 INFO - {'append_to_log': False, 16:31:46 INFO - 'base_work_dir': '/builds/slave/test', 16:31:46 INFO - 'blob_upload_branch': 'Mozilla-Beta', 16:31:46 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:31:46 INFO - 'branch': 'Mozilla-Beta', 16:31:46 INFO - 'buildbot_json_path': 'buildprops.json', 16:31:46 INFO - 'config_files': ('talos/linux_config.py',), 16:31:46 INFO - 'default_actions': ('clobber', 16:31:46 INFO - 'read-buildbot-config', 16:31:46 INFO - 'download-and-extract', 16:31:46 INFO - 'populate-webroot', 16:31:46 INFO - 'create-virtualenv', 16:31:46 INFO - 'install', 16:31:46 INFO - 'run-tests'), 16:31:46 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:31:46 INFO - 'download_minidump_stackwalk': True, 16:31:46 INFO - 'download_symbols': 'ondemand', 16:31:46 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:31:46 INFO - 'tooltool.py': '/tools/tooltool.py', 16:31:46 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:31:46 INFO - '/tools/misc-python/virtualenv.py')}, 16:31:46 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:31:46 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:31:46 INFO - 'installer_path': 'installer.exe', 16:31:46 INFO - 'log_level': 'info', 16:31:46 INFO - 'log_name': 'talos', 16:31:46 INFO - 'log_to_console': True, 16:31:46 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 16:31:46 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 16:31:46 INFO - 'opt_config_files': (), 16:31:46 INFO - 'pip_index': False, 16:31:46 INFO - 'sps_profile': False, 16:31:46 INFO - 'sps_profile_interval': 0, 16:31:46 INFO - 'suite': 'dromaeojs-e10s', 16:31:46 INFO - 'system_bits': '32', 16:31:46 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:31:46 INFO - 'title': 'talos-linux64-ix-018', 16:31:46 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:31:46 INFO - 'use_talos_json': True, 16:31:46 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:31:46 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:31:46 INFO - 'work_dir': 'build'} 16:31:46 INFO - ##### 16:31:46 INFO - ##### Running clobber step. 16:31:46 INFO - ##### 16:31:46 INFO - Running pre-action listener: _resource_record_pre_action 16:31:46 INFO - Running main action method: clobber 16:31:46 INFO - rmtree: /builds/slave/test/build 16:31:46 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:31:46 INFO - Running post-action listener: _resource_record_post_action 16:31:46 INFO - ##### 16:31:46 INFO - ##### Finished clobber step (success) 16:31:46 INFO - ##### 16:31:46 INFO - ##### 16:31:46 INFO - ##### Running read-buildbot-config step. 16:31:46 INFO - ##### 16:31:46 INFO - Running pre-action listener: _resource_record_pre_action 16:31:46 INFO - Running main action method: read_buildbot_config 16:31:46 INFO - Using buildbot properties: 16:31:46 INFO - { 16:31:46 INFO - "project": "", 16:31:46 INFO - "product": "firefox", 16:31:46 INFO - "script_repo_revision": "production", 16:31:46 INFO - "builddir": "mozilla-beta_ubuntu64_hw_test-dromaeojs-e10s", 16:31:46 INFO - "repository": "", 16:31:46 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-beta pgo talos dromaeojs-e10s", 16:31:46 INFO - "buildid": "20160801123750", 16:31:46 INFO - "pgo_build": "True", 16:31:46 INFO - "basedir": "/builds/slave/test", 16:31:46 INFO - "buildnumber": 192, 16:31:46 INFO - "slavename": "talos-linux64-ix-018", 16:31:46 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 16:31:46 INFO - "platform": "ubuntu64_hw", 16:31:46 INFO - "scheduler": "tests-mozilla-beta-linux64-pgo-talos", 16:31:46 INFO - "branch": "mozilla-beta", 16:31:46 INFO - "repo_path": "releases/mozilla-beta", 16:31:46 INFO - "revision": "3998308ce6053f95dbb3bd814a72eaafb7b31fae", 16:31:46 INFO - "stage_platform": "linux64-pgo", 16:31:46 INFO - "builduid": "5a38664472d84844bb6ee371e0c4ddcd", 16:31:46 INFO - "slavebuilddir": "test" 16:31:46 INFO - } 16:31:46 INFO - Found installer url https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2. 16:31:46 INFO - Running post-action listener: _resource_record_post_action 16:31:46 INFO - ##### 16:31:46 INFO - ##### Finished read-buildbot-config step (success) 16:31:46 INFO - ##### 16:31:46 INFO - ##### 16:31:46 INFO - ##### Running download-and-extract step. 16:31:46 INFO - ##### 16:31:46 INFO - Running pre-action listener: _resource_record_pre_action 16:31:46 INFO - Running main action method: download_and_extract 16:31:46 INFO - mkdir: /builds/slave/test/build/tests 16:31:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:46 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 16:31:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:31:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:31:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:31:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json'}, attempt #1 16:31:47 INFO - Downloaded 1327 bytes. 16:31:47 INFO - Reading from file /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.test_packages.json 16:31:47 INFO - Using the following test package requirements: 16:31:47 INFO - {u'common': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 16:31:47 INFO - u'cppunittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.cppunittest.tests.zip'], 16:31:47 INFO - u'gtest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.gtest.tests.zip'], 16:31:47 INFO - u'jittest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'jsshell-linux-x86_64.zip'], 16:31:47 INFO - u'mochitest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.mochitest.tests.zip'], 16:31:47 INFO - u'mozbase': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'], 16:31:47 INFO - u'reftest': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.reftest.tests.zip'], 16:31:47 INFO - u'talos': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'], 16:31:47 INFO - u'web-platform': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.web-platform.tests.zip'], 16:31:47 INFO - u'xpcshell': [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', 16:31:47 INFO - u'firefox-49.0.en-US.linux-x86_64.xpcshell.tests.zip']} 16:31:47 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 16:31:47 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')]} 16:31:47 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:31:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:31:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:31:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:31:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:31:48 INFO - Downloaded 21967605 bytes. 16:31:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:31:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:31:49 INFO - Return code: 0 16:31:49 INFO - Downloading packages: [u'firefox-49.0.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 16:31:49 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')]} 16:31:49 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 16:31:49 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:31:49 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:31:49 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip 16:31:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip'}, attempt #1 16:31:50 INFO - Downloaded 21967605 bytes. 16:31:50 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:31:50 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 16:31:51 INFO - Return code: 0 16:31:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:51 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 16:31:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 16:31:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 16:31:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip 16:31:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 16:31:52 INFO - Downloaded 10833369 bytes. 16:31:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 16:31:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 16:31:52 INFO - Return code: 0 16:31:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:52 INFO - https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 16:31:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:31:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:31:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:31:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2'}, attempt #1 16:31:54 INFO - Downloaded 55283573 bytes. 16:31:54 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:31:54 INFO - mkdir: /builds/slave/test/properties 16:31:54 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:31:54 INFO - Writing to file /builds/slave/test/properties/build_url 16:31:54 INFO - Contents: 16:31:54 INFO - build_url:https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 16:31:54 INFO - Running post-action listener: _resource_record_post_action 16:31:54 INFO - Running post-action listener: set_extra_try_arguments 16:31:54 INFO - ##### 16:31:54 INFO - ##### Finished download-and-extract step (success) 16:31:54 INFO - ##### 16:31:54 INFO - ##### 16:31:54 INFO - ##### Running populate-webroot step. 16:31:54 INFO - ##### 16:31:54 INFO - Running pre-action listener: _resource_record_pre_action 16:31:54 INFO - Running main action method: populate_webroot 16:31:54 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:31:54 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:31:54 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:31:54 INFO - '--tppagecycles', 16:31:54 INFO - '7'], 16:31:54 INFO - 'tests': ['tsvgm']}}, 16:31:54 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:31:54 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:31:54 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:31:54 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:31:54 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:54 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:31:54 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['damp', 'tps']}, 16:31:54 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'tests': ['damp', 'tps']}, 16:31:54 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['dromaeo_dom']}, 16:31:54 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:31:54 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['a11yr', 16:31:54 INFO - 'ts_paint', 16:31:54 INFO - 'tpaint', 16:31:54 INFO - 'sessionrestore', 16:31:54 INFO - 'sessionrestore_no_auto_restore', 16:31:54 INFO - 'tabpaint']}, 16:31:54 INFO - 'other-e10s': {'tests': ['a11yr', 16:31:54 INFO - 'ts_paint', 16:31:54 INFO - 'tpaint', 16:31:54 INFO - 'sessionrestore', 16:31:54 INFO - 'sessionrestore_no_auto_restore', 16:31:54 INFO - 'tabpaint']}, 16:31:54 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:31:54 INFO - 'ts_paint', 16:31:54 INFO - 'tpaint', 16:31:54 INFO - 'sessionrestore', 16:31:54 INFO - 'sessionrestore_no_auto_restore', 16:31:54 INFO - 'tabpaint']}, 16:31:54 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:31:54 INFO - 'ts_paint', 16:31:54 INFO - 'tpaint', 16:31:54 INFO - 'sessionrestore', 16:31:54 INFO - 'sessionrestore_no_auto_restore', 16:31:54 INFO - 'tabpaint']}, 16:31:54 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['a11yr', 16:31:54 INFO - 'ts_paint', 16:31:54 INFO - 'tpaint', 16:31:54 INFO - 'sessionrestore', 16:31:54 INFO - 'sessionrestore_no_auto_restore', 16:31:54 INFO - 'tabpaint']}, 16:31:54 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['a11yr', 16:31:54 INFO - 'ts_paint', 16:31:54 INFO - 'tpaint', 16:31:54 INFO - 'sessionrestore', 16:31:54 INFO - 'sessionrestore_no_auto_restore', 16:31:54 INFO - 'tabpaint']}, 16:31:54 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['tsvgx', 16:31:54 INFO - 'tsvgr_opacity', 16:31:54 INFO - 'tart', 16:31:54 INFO - 'tscrollx', 16:31:54 INFO - 'cart']}, 16:31:54 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:31:54 INFO - 'tsvgr_opacity', 16:31:54 INFO - 'tart', 16:31:54 INFO - 'tscrollx', 16:31:54 INFO - 'cart']}, 16:31:54 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'talos_options': ['--disable-e10s'], 16:31:54 INFO - 'tests': ['tp5o']}, 16:31:54 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'tests': ['tp5o']}, 16:31:54 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'talos_options': ['--disable-e10s', 16:31:54 INFO - '--xperf_path', 16:31:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:54 INFO - 'tests': ['tp5n']}, 16:31:54 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:31:54 INFO - 'talos_options': ['--xperf_path', 16:31:54 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:31:54 INFO - 'tests': ['tp5n']}}, 16:31:54 INFO - 'talos.zip': {'path': '', 16:31:54 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:31:54 INFO - Running post-action listener: _resource_record_post_action 16:31:54 INFO - ##### 16:31:54 INFO - ##### Finished populate-webroot step (success) 16:31:54 INFO - ##### 16:31:54 INFO - ##### 16:31:54 INFO - ##### Running create-virtualenv step. 16:31:54 INFO - ##### 16:31:54 INFO - Running pre-action listener: _resource_record_pre_action 16:31:54 INFO - Running main action method: create_virtualenv 16:31:54 INFO - Creating virtualenv /builds/slave/test/build/venv 16:31:54 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 16:31:54 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:31:55 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:31:55 INFO - Using real prefix '/usr' 16:31:55 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:31:55 INFO - Installing distribute.............................................................................................................................................................................................done. 16:31:57 INFO - Installing pip.................done. 16:31:57 INFO - Return code: 0 16:31:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:57 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:31:57 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 16:31:57 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 16:31:57 INFO - Using env: {'DISPLAY': ':0', 16:31:57 INFO - 'HOME': '/home/cltbld', 16:31:57 INFO - 'LANG': 'en_US.UTF-8', 16:31:57 INFO - 'LANGUAGE': 'en_US:en', 16:31:57 INFO - 'LOGNAME': 'cltbld', 16:31:57 INFO - 'MAIL': '/var/mail/cltbld', 16:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:57 INFO - 'MOZ_NO_REMOTE': '1', 16:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:57 INFO - 'NO_EM_RESTART': '1', 16:31:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:57 INFO - 'PWD': '/builds/slave/test', 16:31:57 INFO - 'SHELL': '/bin/bash', 16:31:57 INFO - 'SHLVL': '1', 16:31:57 INFO - 'TERM': 'linux', 16:31:57 INFO - 'TMOUT': '86400', 16:31:57 INFO - 'USER': 'cltbld', 16:31:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:57 INFO - '_': '/tools/buildbot/bin/python'} 16:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:57 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 16:31:57 INFO - Cleaning up... 16:31:57 INFO - Return code: 0 16:31:57 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:31:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:31:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:31:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:31:57 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:31:57 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 16:31:57 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 16:31:57 INFO - Using env: {'DISPLAY': ':0', 16:31:57 INFO - 'HOME': '/home/cltbld', 16:31:57 INFO - 'LANG': 'en_US.UTF-8', 16:31:57 INFO - 'LANGUAGE': 'en_US:en', 16:31:57 INFO - 'LOGNAME': 'cltbld', 16:31:57 INFO - 'MAIL': '/var/mail/cltbld', 16:31:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:31:57 INFO - 'MOZ_NO_REMOTE': '1', 16:31:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:31:57 INFO - 'NO_EM_RESTART': '1', 16:31:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:31:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:31:57 INFO - 'PWD': '/builds/slave/test', 16:31:57 INFO - 'SHELL': '/bin/bash', 16:31:57 INFO - 'SHLVL': '1', 16:31:57 INFO - 'TERM': 'linux', 16:31:57 INFO - 'TMOUT': '86400', 16:31:57 INFO - 'USER': 'cltbld', 16:31:57 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:31:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:31:57 INFO - '_': '/tools/buildbot/bin/python'} 16:31:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:31:57 INFO - Downloading/unpacking psutil>=3.1.1 16:31:57 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 16:31:57 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 16:31:57 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 16:31:57 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 16:31:57 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 16:31:57 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 16:31:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:31:59 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:31:59 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:31:59 INFO - Installing collected packages: psutil 16:31:59 INFO - Running setup.py install for psutil 16:31:59 INFO - building 'psutil._psutil_linux' extension 16:31:59 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 16:32:00 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 16:32:00 INFO - building 'psutil._psutil_posix' extension 16:32:00 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 16:32:00 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 16:32:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:32:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:32:00 INFO - Successfully installed psutil 16:32:00 INFO - Cleaning up... 16:32:00 INFO - Return code: 0 16:32:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:00 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:00 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 16:32:00 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 16:32:00 INFO - Using env: {'DISPLAY': ':0', 16:32:00 INFO - 'HOME': '/home/cltbld', 16:32:00 INFO - 'LANG': 'en_US.UTF-8', 16:32:00 INFO - 'LANGUAGE': 'en_US:en', 16:32:00 INFO - 'LOGNAME': 'cltbld', 16:32:00 INFO - 'MAIL': '/var/mail/cltbld', 16:32:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:00 INFO - 'MOZ_NO_REMOTE': '1', 16:32:00 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:00 INFO - 'NO_EM_RESTART': '1', 16:32:00 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:00 INFO - 'PWD': '/builds/slave/test', 16:32:00 INFO - 'SHELL': '/bin/bash', 16:32:00 INFO - 'SHLVL': '1', 16:32:00 INFO - 'TERM': 'linux', 16:32:00 INFO - 'TMOUT': '86400', 16:32:00 INFO - 'USER': 'cltbld', 16:32:00 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:00 INFO - '_': '/tools/buildbot/bin/python'} 16:32:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:32:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:32:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:32:02 INFO - Installing collected packages: mozsystemmonitor 16:32:02 INFO - Running setup.py install for mozsystemmonitor 16:32:02 INFO - Successfully installed mozsystemmonitor 16:32:02 INFO - Cleaning up... 16:32:02 INFO - Return code: 0 16:32:02 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 16:32:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 16:32:02 INFO - Using env: {'DISPLAY': ':0', 16:32:02 INFO - 'HOME': '/home/cltbld', 16:32:02 INFO - 'LANG': 'en_US.UTF-8', 16:32:02 INFO - 'LANGUAGE': 'en_US:en', 16:32:02 INFO - 'LOGNAME': 'cltbld', 16:32:02 INFO - 'MAIL': '/var/mail/cltbld', 16:32:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:02 INFO - 'MOZ_NO_REMOTE': '1', 16:32:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:02 INFO - 'NO_EM_RESTART': '1', 16:32:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:02 INFO - 'PWD': '/builds/slave/test', 16:32:02 INFO - 'SHELL': '/bin/bash', 16:32:02 INFO - 'SHLVL': '1', 16:32:02 INFO - 'TERM': 'linux', 16:32:02 INFO - 'TMOUT': '86400', 16:32:02 INFO - 'USER': 'cltbld', 16:32:02 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:02 INFO - '_': '/tools/buildbot/bin/python'} 16:32:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:02 INFO - Downloading/unpacking jsonschema==2.5.1 16:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:32:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:05 INFO - Downloading functools32-3.2.3-2.tar.gz 16:32:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:32:05 INFO - warning: no files found matching '*.txt' 16:32:05 INFO - no previously-included directories found matching 'build' 16:32:05 INFO - no previously-included directories found matching 'dist' 16:32:05 INFO - no previously-included directories found matching '.git*' 16:32:05 INFO - Installing collected packages: jsonschema, functools32 16:32:05 INFO - Running setup.py install for functools32 16:32:05 INFO - warning: no files found matching '*.txt' 16:32:05 INFO - no previously-included directories found matching 'build' 16:32:05 INFO - no previously-included directories found matching 'dist' 16:32:05 INFO - no previously-included directories found matching '.git*' 16:32:05 INFO - Successfully installed jsonschema functools32 16:32:05 INFO - Cleaning up... 16:32:05 INFO - Return code: 0 16:32:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:05 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:05 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 16:32:05 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 16:32:05 INFO - Using env: {'DISPLAY': ':0', 16:32:05 INFO - 'HOME': '/home/cltbld', 16:32:05 INFO - 'LANG': 'en_US.UTF-8', 16:32:05 INFO - 'LANGUAGE': 'en_US:en', 16:32:05 INFO - 'LOGNAME': 'cltbld', 16:32:05 INFO - 'MAIL': '/var/mail/cltbld', 16:32:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:05 INFO - 'MOZ_NO_REMOTE': '1', 16:32:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:05 INFO - 'NO_EM_RESTART': '1', 16:32:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:05 INFO - 'PWD': '/builds/slave/test', 16:32:05 INFO - 'SHELL': '/bin/bash', 16:32:05 INFO - 'SHLVL': '1', 16:32:05 INFO - 'TERM': 'linux', 16:32:05 INFO - 'TMOUT': '86400', 16:32:05 INFO - 'USER': 'cltbld', 16:32:05 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:05 INFO - '_': '/tools/buildbot/bin/python'} 16:32:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:05 INFO - Downloading/unpacking blobuploader==1.2.4 16:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - Downloading blobuploader-1.2.4.tar.gz 16:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:32:07 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:32:07 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:08 INFO - Downloading docopt-0.6.1.tar.gz 16:32:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:32:08 INFO - Installing collected packages: blobuploader, requests, docopt 16:32:08 INFO - Running setup.py install for blobuploader 16:32:08 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:32:08 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:32:08 INFO - Running setup.py install for requests 16:32:08 INFO - Running setup.py install for docopt 16:32:08 INFO - Successfully installed blobuploader requests docopt 16:32:08 INFO - Cleaning up... 16:32:08 INFO - Return code: 0 16:32:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32: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')]} 16:32:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32: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')]} 16:32:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:08 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:08 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 16:32:08 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 16:32:08 INFO - Using env: {'DISPLAY': ':0', 16:32:08 INFO - 'HOME': '/home/cltbld', 16:32:08 INFO - 'LANG': 'en_US.UTF-8', 16:32:08 INFO - 'LANGUAGE': 'en_US:en', 16:32:08 INFO - 'LOGNAME': 'cltbld', 16:32:08 INFO - 'MAIL': '/var/mail/cltbld', 16:32:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:08 INFO - 'MOZ_NO_REMOTE': '1', 16:32:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:08 INFO - 'NO_EM_RESTART': '1', 16:32:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:08 INFO - 'PWD': '/builds/slave/test', 16:32:08 INFO - 'SHELL': '/bin/bash', 16:32:08 INFO - 'SHLVL': '1', 16:32:08 INFO - 'TERM': 'linux', 16:32:08 INFO - 'TMOUT': '86400', 16:32:08 INFO - 'USER': 'cltbld', 16:32:08 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:08 INFO - '_': '/tools/buildbot/bin/python'} 16:32:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:09 INFO - Running setup.py (path:/tmp/pip-c25Vtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:09 INFO - Running setup.py (path:/tmp/pip-VGaJ2d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:09 INFO - Running setup.py (path:/tmp/pip-JJuktz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:09 INFO - Running setup.py (path:/tmp/pip-3dLxQq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:09 INFO - Running setup.py (path:/tmp/pip-ezJJv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:09 INFO - Running setup.py (path:/tmp/pip-b8H07Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:09 INFO - Running setup.py (path:/tmp/pip-vS_Kph-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:09 INFO - Running setup.py (path:/tmp/pip-_V_oxo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:09 INFO - Running setup.py (path:/tmp/pip-7WqnrM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:09 INFO - Running setup.py (path:/tmp/pip-3kBV_C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:09 INFO - Running setup.py (path:/tmp/pip-BNAdUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:09 INFO - Running setup.py (path:/tmp/pip-lguAtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:09 INFO - Running setup.py (path:/tmp/pip-ont0X7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:10 INFO - Running setup.py (path:/tmp/pip-HceXCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:10 INFO - Running setup.py (path:/tmp/pip-e1sJGD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:10 INFO - Running setup.py (path:/tmp/pip-drUAVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:10 INFO - Running setup.py (path:/tmp/pip-tCZlcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:10 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:32:10 INFO - Running setup.py install for manifestparser 16:32:10 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:32:10 INFO - Running setup.py install for mozcrash 16:32:10 INFO - Running setup.py install for mozdebug 16:32:10 INFO - Running setup.py install for mozdevice 16:32:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:32:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:32:10 INFO - Running setup.py install for mozfile 16:32:10 INFO - Running setup.py install for mozhttpd 16:32:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:32:10 INFO - Running setup.py install for mozinfo 16:32:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:32:10 INFO - Running setup.py install for mozInstall 16:32:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Running setup.py install for mozleak 16:32:11 INFO - Running setup.py install for mozlog 16:32:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Running setup.py install for moznetwork 16:32:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Running setup.py install for mozprocess 16:32:11 INFO - Running setup.py install for mozprofile 16:32:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Running setup.py install for mozrunner 16:32:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:32:11 INFO - Running setup.py install for mozscreenshot 16:32:11 INFO - Running setup.py install for moztest 16:32:11 INFO - Running setup.py install for mozversion 16:32:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:32:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:32:12 INFO - Cleaning up... 16:32:12 INFO - Return code: 0 16:32:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:12 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')]} 16:32:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:12 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')]} 16:32:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:12 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:12 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 16:32:12 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 16:32:12 INFO - Using env: {'DISPLAY': ':0', 16:32:12 INFO - 'HOME': '/home/cltbld', 16:32:12 INFO - 'LANG': 'en_US.UTF-8', 16:32:12 INFO - 'LANGUAGE': 'en_US:en', 16:32:12 INFO - 'LOGNAME': 'cltbld', 16:32:12 INFO - 'MAIL': '/var/mail/cltbld', 16:32:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:12 INFO - 'MOZ_NO_REMOTE': '1', 16:32:12 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:12 INFO - 'NO_EM_RESTART': '1', 16:32:12 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:12 INFO - 'PWD': '/builds/slave/test', 16:32:12 INFO - 'SHELL': '/bin/bash', 16:32:12 INFO - 'SHLVL': '1', 16:32:12 INFO - 'TERM': 'linux', 16:32:12 INFO - 'TMOUT': '86400', 16:32:12 INFO - 'USER': 'cltbld', 16:32:12 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:12 INFO - '_': '/tools/buildbot/bin/python'} 16:32:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:32:12 INFO - Running setup.py (path:/tmp/pip-8JvuZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:32:12 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)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:32:12 INFO - Running setup.py (path:/tmp/pip-iyOw4T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:32:12 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)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:32:12 INFO - Running setup.py (path:/tmp/pip-HhqIAX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:32:12 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)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:32:12 INFO - Running setup.py (path:/tmp/pip-lpdabD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:32:12 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)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:32:12 INFO - Running setup.py (path:/tmp/pip-3Z4qoX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:32:12 INFO - Running setup.py (path:/tmp/pip-VtLfjv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:32:12 INFO - Running setup.py (path:/tmp/pip-87Z1iY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:32:12 INFO - Running setup.py (path:/tmp/pip-zx_ftg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:32:12 INFO - Running setup.py (path:/tmp/pip-j2nUsd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:32:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 16:32:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:32:12 INFO - Running setup.py (path:/tmp/pip-MWz2Re-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:32:13 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:32:13 INFO - Running setup.py (path:/tmp/pip-kzwBDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:32:13 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:32:13 INFO - Running setup.py (path:/tmp/pip-u2XfH0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:32:13 INFO - Running setup.py (path:/tmp/pip-CgUCqW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:32:13 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:32:13 INFO - Running setup.py (path:/tmp/pip-59Qgxs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:32:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:13 INFO - Running setup.py (path:/tmp/pip-1VXnRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:32:13 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:32:13 INFO - Running setup.py (path:/tmp/pip-auqclq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:32:13 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)) 16:32:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:32:13 INFO - Running setup.py (path:/tmp/pip-KPM6FM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:32:13 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)) 16:32:13 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)) 16:32:13 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)) 16:32:13 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)) 16:32:13 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)) 16:32:13 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)) 16:32:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:32:13 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 16:32:13 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 16:32:13 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 16:32:13 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 16:32:13 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 16:32:13 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 16:32:15 INFO - Downloading blessings-1.6.tar.gz 16:32:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:32:15 INFO - Installing collected packages: blessings 16:32:15 INFO - Running setup.py install for blessings 16:32:15 INFO - Successfully installed blessings 16:32:15 INFO - Cleaning up... 16:32:15 INFO - Return code: 0 16:32:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:32:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:15 INFO - Reading from file tmpfile_stdout 16:32:15 INFO - Current package versions: 16:32:15 INFO - argparse == 1.2.1 16:32:15 INFO - blessings == 1.6 16:32:15 INFO - blobuploader == 1.2.4 16:32:15 INFO - docopt == 0.6.1 16:32:15 INFO - functools32 == 3.2.3-2 16:32:15 INFO - jsonschema == 2.5.1 16:32:15 INFO - manifestparser == 1.1 16:32:15 INFO - mozInstall == 1.12 16:32:15 INFO - mozcrash == 0.17 16:32:15 INFO - mozdebug == 0.1 16:32:15 INFO - mozdevice == 0.48 16:32:15 INFO - mozfile == 1.2 16:32:15 INFO - mozhttpd == 0.7 16:32:15 INFO - mozinfo == 0.9 16:32:15 INFO - mozleak == 0.1 16:32:15 INFO - mozlog == 3.2 16:32:15 INFO - moznetwork == 0.27 16:32:15 INFO - mozprocess == 0.23 16:32:15 INFO - mozprofile == 0.28 16:32:15 INFO - mozrunner == 6.12 16:32:15 INFO - mozscreenshot == 0.1 16:32:15 INFO - mozsystemmonitor == 0.3 16:32:15 INFO - moztest == 0.7 16:32:15 INFO - mozversion == 1.4 16:32:15 INFO - psutil == 3.1.1 16:32:15 INFO - requests == 1.2.3 16:32:15 INFO - wsgiref == 0.1.2 16:32:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:15 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:15 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 16:32:15 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 16:32:15 INFO - Using env: {'DISPLAY': ':0', 16:32:15 INFO - 'HOME': '/home/cltbld', 16:32:15 INFO - 'LANG': 'en_US.UTF-8', 16:32:15 INFO - 'LANGUAGE': 'en_US:en', 16:32:15 INFO - 'LOGNAME': 'cltbld', 16:32:15 INFO - 'MAIL': '/var/mail/cltbld', 16:32:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:15 INFO - 'MOZ_NO_REMOTE': '1', 16:32:15 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:15 INFO - 'NO_EM_RESTART': '1', 16:32:15 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:15 INFO - 'PWD': '/builds/slave/test', 16:32:15 INFO - 'SHELL': '/bin/bash', 16:32:15 INFO - 'SHLVL': '1', 16:32:15 INFO - 'TERM': 'linux', 16:32:15 INFO - 'TMOUT': '86400', 16:32:15 INFO - 'USER': 'cltbld', 16:32:15 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:15 INFO - '_': '/tools/buildbot/bin/python'} 16:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 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)) 16:32:16 INFO - Cleaning up... 16:32:16 INFO - Return code: 0 16:32:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:16 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:16 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 16:32:16 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 16:32:16 INFO - Using env: {'DISPLAY': ':0', 16:32:16 INFO - 'HOME': '/home/cltbld', 16:32:16 INFO - 'LANG': 'en_US.UTF-8', 16:32:16 INFO - 'LANGUAGE': 'en_US:en', 16:32:16 INFO - 'LOGNAME': 'cltbld', 16:32:16 INFO - 'MAIL': '/var/mail/cltbld', 16:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:16 INFO - 'MOZ_NO_REMOTE': '1', 16:32:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:16 INFO - 'NO_EM_RESTART': '1', 16:32:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:16 INFO - 'PWD': '/builds/slave/test', 16:32:16 INFO - 'SHELL': '/bin/bash', 16:32:16 INFO - 'SHLVL': '1', 16:32:16 INFO - 'TERM': 'linux', 16:32:16 INFO - 'TMOUT': '86400', 16:32:16 INFO - 'USER': 'cltbld', 16:32:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:16 INFO - '_': '/tools/buildbot/bin/python'} 16:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:32:16 INFO - Cleaning up... 16:32:16 INFO - Return code: 0 16:32:16 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 16:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:32:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:32:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:32:16 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 0x7f339ca44f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16a58a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1770350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, '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': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', '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 16:32:16 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 16:32:16 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 16:32:16 INFO - Using env: {'DISPLAY': ':0', 16:32:16 INFO - 'HOME': '/home/cltbld', 16:32:16 INFO - 'LANG': 'en_US.UTF-8', 16:32:16 INFO - 'LANGUAGE': 'en_US:en', 16:32:16 INFO - 'LOGNAME': 'cltbld', 16:32:16 INFO - 'MAIL': '/var/mail/cltbld', 16:32:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:16 INFO - 'MOZ_NO_REMOTE': '1', 16:32:16 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:16 INFO - 'NO_EM_RESTART': '1', 16:32:16 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:16 INFO - 'PWD': '/builds/slave/test', 16:32:16 INFO - 'SHELL': '/bin/bash', 16:32:16 INFO - 'SHLVL': '1', 16:32:16 INFO - 'TERM': 'linux', 16:32:16 INFO - 'TMOUT': '86400', 16:32:16 INFO - 'USER': 'cltbld', 16:32:16 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:16 INFO - '_': '/tools/buildbot/bin/python'} 16:32:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:32:16 INFO - Downloading/unpacking flake8 16:32:16 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 16:32:16 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 16:32:16 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 16:32:16 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 16:32:16 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 16:32:16 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 16:32:18 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 16:32:18 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:18 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 16:32:18 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:18 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:32:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:32:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:32:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 16:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:32:19 INFO - no previously-included directories found matching 'docs/_build' 16:32:19 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 16:32:19 INFO - Running setup.py install for pep8 16:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 16:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 16:32:19 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 16:32:19 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 16:32:19 INFO - no previously-included directories found matching 'docs/_build' 16:32:19 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 16:32:19 INFO - Successfully installed flake8 mccabe pyflakes pep8 16:32:19 INFO - Cleaning up... 16:32:19 INFO - Return code: 0 16:32:19 INFO - Running post-action listener: _resource_record_post_action 16:32:19 INFO - Running post-action listener: _start_resource_monitoring 16:32:19 INFO - Starting resource monitoring. 16:32:19 INFO - ##### 16:32:19 INFO - ##### Finished create-virtualenv step (success) 16:32:19 INFO - ##### 16:32:19 INFO - ##### 16:32:19 INFO - ##### Running install step. 16:32:19 INFO - ##### 16:32:19 INFO - Running pre-action listener: _resource_record_pre_action 16:32:19 INFO - Running main action method: install 16:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:32:19 INFO - Reading from file tmpfile_stdout 16:32:19 INFO - Detecting whether we're running mozinstall >=1.0... 16:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:32:19 INFO - Reading from file tmpfile_stdout 16:32:19 INFO - Output received: 16:32:19 INFO - Usage: mozinstall [options] installer 16:32:19 INFO - Options: 16:32:19 INFO - -h, --help show this help message and exit 16:32:19 INFO - -d DEST, --destination=DEST 16:32:19 INFO - Directory to install application into. [default: 16:32:19 INFO - "/builds/slave/test"] 16:32:19 INFO - --app=APP Application being installed. [default: firefox] 16:32:19 INFO - mkdir: /builds/slave/test/build/application 16:32:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 16:32:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 16:32:32 INFO - Reading from file tmpfile_stdout 16:32:32 INFO - Output received: 16:32:32 INFO - /builds/slave/test/build/application/firefox/firefox 16:32:32 INFO - Running post-action listener: _resource_record_post_action 16:32:32 INFO - ##### 16:32:32 INFO - ##### Finished install step (success) 16:32:32 INFO - ##### 16:32:32 INFO - ##### 16:32:32 INFO - ##### Running run-tests step. 16:32:32 INFO - ##### 16:32:32 INFO - Running pre-action listener: _resource_record_pre_action 16:32:32 INFO - Running main action method: run_tests 16:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:32:32 INFO - Python 2.7.3 16:32:32 INFO - Return code: 0 16:32:32 INFO - grabbing minidump binary from tooltool 16:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 16:32:32 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 0x17ff1c0>, '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 0x18000c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1800550>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:32:32 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 16:32:32 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 16:32:32 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 16:32:32 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:32:32 INFO - Return code: 0 16:32:32 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 16:32:32 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:32:32 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:32:32 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 16:32:32 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 16:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 16:32:33 INFO - Return code: 0 16:32:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:32:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-018 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:32:33 INFO - Using env: {'DISPLAY': ':0', 16:32:33 INFO - 'HOME': '/home/cltbld', 16:32:33 INFO - 'LANG': 'en_US.UTF-8', 16:32:33 INFO - 'LANGUAGE': 'en_US:en', 16:32:33 INFO - 'LOGNAME': 'cltbld', 16:32:33 INFO - 'MAIL': '/var/mail/cltbld', 16:32:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:32:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 16:32:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:32:33 INFO - 'MOZ_NO_REMOTE': '1', 16:32:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:32:33 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 16:32:33 INFO - 'NO_EM_RESTART': '1', 16:32:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 16:32:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:32:33 INFO - 'PWD': '/builds/slave/test', 16:32:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:32:33 INFO - 'SHELL': '/bin/bash', 16:32:33 INFO - 'SHLVL': '1', 16:32:33 INFO - 'TERM': 'linux', 16:32:33 INFO - 'TMOUT': '86400', 16:32:33 INFO - 'USER': 'cltbld', 16:32:33 INFO - 'XDG_SESSION_COOKIE': '62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966', 16:32:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:32:33 INFO - '_': '/tools/buildbot/bin/python'} 16:32:33 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-018', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:32:34 INFO - mozversion application_buildid: 20160801123750 16:32:34 INFO - mozversion application_changeset: 3998308ce6053f95dbb3bd814a72eaafb7b31fae 16:32:34 INFO - mozversion application_display_name: Firefox 16:32:34 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:32:34 INFO - mozversion application_name: Firefox 16:32:34 INFO - mozversion application_remotingname: firefox 16:32:34 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 16:32:34 INFO - mozversion application_vendor: Mozilla 16:32:34 INFO - mozversion application_version: 49.0 16:32:34 INFO - mozversion platform_buildid: 20160801123750 16:32:34 INFO - mozversion platform_changeset: 3998308ce6053f95dbb3bd814a72eaafb7b31fae 16:32:34 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 16:32:34 INFO - mozversion platform_version: 49.0 16:32:34 INFO - using testdate: 1470094354 16:32:34 INFO - actual date: 1470094354 16:32:34 INFO - starting webserver on 'localhost:39396' 16:32:34 INFO - SUITE-START | Running 2 tests 16:32:34 INFO - TEST-START | dromaeo_css 16:32:34 INFO - operating with platform_type : linux_ 16:32:34 INFO - Initialising browser for dromaeo_css test... 16:32:34 INFO - TEST-INFO | started process 20450 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6tN8lP/profile http://localhost:39396/getInfo.html) 16:32:38 INFO - PROCESS | 20450 | __metrics Screen width/height:1600/1200 16:32:38 INFO - PROCESS | 20450 | colorDepth:24 16:32:38 INFO - PROCESS | 20450 | Browser inner width/height: 1024/697 16:32:38 INFO - PROCESS | 20450 | __metrics 16:32:38 ERROR - PROCESS | 20450 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 16:32:39 INFO - TEST-INFO | 20450: exit 0 16:32:39 INFO - Browser initialized. 16:32:39 INFO - Running cycle 1/1 for dromaeo_css test... 16:32:39 INFO - TEST-INFO | started process 20577 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmp6tN8lP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:32:51 INFO - PROCESS | 20577 | RSS: Main: 153460736 16:32:51 INFO - PROCESS | 20577 | 16:33:40 INFO - PROCESS | 20577 | 1470094420913 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] 16:35:48 INFO - PROCESS | 20577 | Cycle 1(1): loaded http://localhost:39396/tests/dromaeo/cssquery-dojo.html (next: http://localhost:39396/tests/dromaeo/cssquery-ext.html) 16:35:49 INFO - PROCESS | 20577 | RSS: Main: 157323264 16:35:49 INFO - PROCESS | 20577 | 16:38:51 INFO - PROCESS | 20577 | Cycle 1(1): loaded http://localhost:39396/tests/dromaeo/cssquery-ext.html (next: http://localhost:39396/tests/dromaeo/cssquery-jquery.html) 16:38:52 INFO - PROCESS | 20577 | RSS: Main: 155529216 16:38:52 INFO - PROCESS | 20577 | 16:41:54 INFO - PROCESS | 20577 | Cycle 1(1): loaded http://localhost:39396/tests/dromaeo/cssquery-jquery.html (next: http://localhost:39396/tests/dromaeo/cssquery-mootools.html) 16:41:54 INFO - PROCESS | 20577 | RSS: Main: 154849280 16:41:54 INFO - PROCESS | 20577 | 16:44:56 INFO - PROCESS | 20577 | Cycle 1(1): loaded http://localhost:39396/tests/dromaeo/cssquery-mootools.html (next: http://localhost:39396/tests/dromaeo/cssquery-prototype.html) 16:44:57 INFO - PROCESS | 20577 | RSS: Main: 156262400 16:44:57 INFO - PROCESS | 20577 | 16:47:59 INFO - PROCESS | 20577 | Cycle 1(1): loaded http://localhost:39396/tests/dromaeo/cssquery-prototype.html (next: http://localhost:39396/tests/dromaeo/cssquery-yui.html) 16:48:00 INFO - PROCESS | 20577 | RSS: Main: 154906624 16:48:00 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | Cycle 1(1): loaded http://localhost:39396/tests/dromaeo/cssquery-yui.html (next: http://localhost:39396/tests/dromaeo/cssquery-dojo.html) 16:51:02 INFO - PROCESS | 20577 | RSS: Main: 158957568 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | __start_tp_report 16:51:02 INFO - PROCESS | 20577 | _x_x_mozilla_page_load 16:51:02 INFO - PROCESS | 20577 | _x_x_mozilla_page_load_details 16:51:02 INFO - PROCESS | 20577 | |i|pagename|runs| 16:51:02 INFO - PROCESS | 20577 | |0;dojo.html;3354.3962086824367;3359.7648164628454;3374.072130164205;3387.8306084695773;3399.711024562912;5515.882297322176;5555.194496797967;5568.0812665910125;5595.300587426572;5606.915896261559;5567.6659400436;5580.218769372291;5631.549476041917;5645.745941432635;5649.265595472582;4988.800447982077;5014.24786282057;5070.2141168119;5096.439391666932;5102.591792656597;4951.108800415924;5011.574016208627;5046.116929537335;5088.313077734514;5128.974355128238;5008.524190201925;5013.4234563025175;5038.168702164138;5041.067402530525;5080.187170052793;5022.447530771612;5084.567811736007;5084.9745751271375;5087.338645976037;5098.337216161875;4817.56641902227;4999.4250661174065;5012.649114561982;5037;5065.594752419835;5331.067063263913;5369.677819330814;5397.595180361489;5439.211314359396;5448.128299472065;5954.315253745788;5983.880322393571;5997.100434934795;6002.759889604411;6035;6052.334243233285;6057.121717350959;6058;6064.090386442069;6066.545009124334;5499.532539734128;5559.861003474945;5603.187537806995;5608.018596745594;5629.4652008059165;6119.5104391648565;6119.602225855394;6153.353897840751;6160.815175544741;6170.228721409824;5864.296284445893;5865.94134058654;5885.676287804126;5894.2926848778425;5924.7926322578505;5867.266591676041;5876.882462350729;5893.970530147408;5937.079752638348;5995.250593675791;5922.082077278029;5944.6730429826775;5947.910781338283;5956.583039187215;5958.910616340759;5792.102224155263;5950.761969521257;5970.85072873173;5978.133170690226;5986.820395388146;4612.607928326062;4623.861284161413;4642.582167604932;4671.8364857229835;4683.601893838993;4159.958400415957;4181.233393930921;4186.874393768131;4186.995121170897;4210.7473551587;5673.489385955284;5682.886342273214;5855.560832937462;5872.618279811799;5874.441928016831;5871.089981052944;5955.553333500005;5962.731677074542;6054.727537260834;6111.297200821873;5832.825015249549;5836.970815145982;5840.036393994943;5841.094630332305;5853.707314634251;5080.085584594808;5152.433232344439;5223.712695801767;5226.790928362899;5253.711045892436;6168.9691551541955;6293.527985401113;6314.968425157845;6316.905246421307;6322.9051564226575;6064.66644334557;6080.8783824324155;6085;6088.025915853442;6089.512838972783;6027.60820546663;6062.211912451353;6066.787662431793;6257.374262573706;6337.429631333202;5813.418658134153;5846.386129456516;5866.208061911588;5921.171036055042;5941.287045554561;6039.788607398896;6039.939600604113;6042.5770196087615;6047.274327080602;6056.333803281724;5707.828765137053;5760.452756988079;5883.441073098049;5896.233489646319;5912.349641539341;5778.855528611818;5814.912776308443;5858.765649373977;5881.411858814085;5935.40645935403;5776.249087618583;5781.739821707949;5782.768689252551;5790.986577349031;5796.652200867961;5739.454751798572;5845.736941837543;5862.355140934579;5912.586118971631;5917.733701983507;5871.530277577698;5881.32364778056;5882.058870580858;5890.175375447527;5906.970465147647 16:51:02 INFO - PROCESS | 20577 | |1;ext.html;581.9476247137757;650.2457149706341;668.5119862500375;670.9261981182069;675.9831004224897;40198.59801401985;44217.33673994893;44484.77757611215;44593.55406445934;44626.33060504091;44777.77611111948;45418.545814541845;45596.54403455964;45688.54311456893;45831.77084114583;8798;8989.101089891024;8990.775230619221;9019.323550733721;9036.819263614723;8879.95560022199;8930.732078037669;8940.776480588005;8978.147076027766;9011.729648110535;9006.099390060974;9025.323100767437;9033.277337812959;9047.276217902552;9064.501452420147;8947.731568052935;8967;9004.639814407416;9006.324525660602;9049.457032578066;8835.116488351177;8904.421212621159;8917.643294268222;8961.46231226122;8961.910380896174;43148.137037259396;43477.78261108674;43490;43571.78214108941;43611;3817.9882331182257;3826.1391186982983;3887.1836914248042;3949.5260568731937;4295.248331541999;34065.82967085174;34883.82558087219;34976.12559686003;35094.82452587746;35205;4717.056588682277;4788.449328327218;4815.422149342101;4848.539388758098;4895.363602731622;368291;369011.15494422626;369458;369544;370611;62894.37105628977;63038;63279.36720632735;63347.366526334146;63578;40079.79960100181;40479.59520404758;40517.39223911644;42152.57847421548;42166.57833421627;15571.299291531908;15796.605084872741;15809.13049782273;15935.601609959844;15950.920245398698;10903.127749779998;10904.836427453594;10912.61805836792;10931.344119352865;10942.069924056545;20672.689909651366;20782;20833.89583052075;20885.582288354148;21048.894755526126;7501.624918754041;7574.128975167926;7638.618069096523;7662.65518051689;7838.686452541949;1065.7442213868617;1103.3214573037685;1161.988380116205;1182.8521434820648;1188.2157775868059;32934.176645584055;33103.172420689676;33178;33434.66565334315;33754.324913501594;1161.7269941563725;1195.54569263681;1239.9442025108892;1248.6940699528673;1251.8622951475331;17268.395606153725;17423.651526969643;17444.912775436296;17540.473785786446;17551.82448175502;582198;813621;830008;831340;837317;62116;62443.375566244664;62837.68581157065;62918;62951.37048629547;1425.8645428684258;1449.39125567262;1474.800901878254;1488.3302513868587;1522.7715842623475;33307.333853322314;33428.832855835564;33504.497432538046;34060;34453.483197752554;53963.730181348845;58230.4176958225;58883.70558147182;59072.70463647654;63621;487612;489238;493882;494978;495625;46657.06685866332;47333.76333118312;47790.52209477861;47879.281810773566;47883.521164787904;51163.488365115874;51417.485825142765;51886;52693;53085;43484.56515434805;43900.56099439092;44364.556354437336;44470.11059778851;45247.09505809931;43403.34894976641;43916.34125488184;44364.77817610891;44764.32853507265;44803.551964479935;43375.78312108419;43509.78245108754;43830.780846096844;43971.78014109909;46052;37241.25517489581;37824.91962942065;37949.241015180094;38118;38439.807800960814;29284.267893302836;29364.97222597199;29475.263118422212;29523.11430657084;29747.70252297536 16:51:02 INFO - PROCESS | 20577 | |2;jquery.html;409.54407082820563;411.78998710657555;415.7505496701978;416.3213961243173;426.32214778502174;18272.725909111374;19245.133968971393;19939.1027403767;19940.60118797623;20066.598668026632;12396.070294727886;12471.501139954413;12732.427040783165;13001.674958126052;13084;5228.581713462923;5376.059189641816;5399.78400863965;5415.810446634368;5476.4249753775875;5755.107958266475;5788.450097240755;5812.186293918855;5840.269966254218;5845.444682755153;5644.943550564483;5747.339056008571;5759.107338362561;5766.538676905838;5807.680577568253;5694.914576281359;5733.082706766897;5744.511716504102;5759.510441612468;5773.9422605773825;5047.369078865142;5188.11801993662;5198.40218374888;5253.579713622901;5257.973710131463;22500.21249256268;23274.76725232743;23332;23383.4154146146;23657.29028129159;791.9762407127795;2324.5583339165673;2525.939105575644;2646.0474229277443;2881.654201495813;10441.47792610374;10969.23215374924;11137.498812553455;12268.44791984363;15172.393104275816;2000.1099510717736;2000.6998950157447;2016.8487363447653;2052.4253209101475;2087.926922557703;50173;69420.65289673519;82314;84918.5754071238;85673;11395;11725.296482210924;11764.823527647091;11815.704607384712;11952.64142075739;16267;16493.34026638945;16515.256813443182;16595.253213605418;16693;8115.83768324642;8147.959260203661;8265.256126948603;8313.293370063491;8362.372822038249;7590.468667193243;7681.116671582839;7721.112072111779;7747.922520774833;7750.186230445835;4926.251180830754;4976.751162441863;5065;5146.996335714574;5163.251328557338;2029.8985050747403;2079.59806423581;2081.937541873716;2103.2638576498407;2123.9044243009103;977.5698692575244;987.6641941739699;1031.7073413389414;1092.6339676208559;1093.4423444043596;18467.630647386977;19364.70952935707;19365.80634193668;19393.32123375675;19445.416637500897;982.3958265666564;1025.108155904353;1040.2250323509033;1084.5770149641646;1104.100158370925;8815.426997245288;10584.470776461147;10747;10757.193210509087;10766.084882784895;488107.55946220044;490966;497788;504009;507407;86216;88553.55723221472;89890.55054724685;90958.54520727486;91507.54246228727;473.91469535483253;488.5310102301759;492.36730800920645;492.36976669862634;495.61342153120626;11145.442727863736;11413.486393112164;11561.768764624829;11747.823782643438;11962.10284228669;40234.79882600568;40513.18973620452;41330.173396531296;42527.787361063;43250.78374608107;52240.47759522356;52344.47655523548;54778;59404.1089383651;60591.69704151627;8771.736847894574;8828.646854125764;9007.369484136048;9107.180353768194;9241.537923103953;12412.689682757642;12581.87418125807;12602.621921342374;12988.935055324662;13098.541551045668;11100.833487497855;11785.823212651645;11829.822552661544;11992.340421276565;12237.632871013884;11472.820472065658;11562.653120406401;11574.537018519164;11865;11881.168318217642;11508.827367589325;11734.882651173379;11857.11071669611;12011.099167562294;12223.388830558615;8295.95852020736;8554.743357699279;9113.270938325051;9299.907000929905;9356.06439356059;8619.051904290649;11104.944475277573;11646.126540509325;11650.825237621273;11667.299962002307 16:51:02 INFO - PROCESS | 20577 | |3;mootools.html;1602.0307713833133;1624.6344572471196;1818.7544681467998;1867.0534348101307;1891.659501289768;8330.541820199887;9322.347435679505;9398.24814014879;10029.949850250758;10087.899121008788;9542.904570954288;9788.85316720248;9897.4556399398;9918;9921.702348929526;2402.351365131418;2431.9513609727883;2445.510897820443;2445.6331550267423;2457.3979375053136;2395.461021270209;2406.7232268289195;2439.9268021959283;2448.4980578981267;2453.521563295158;2435.038159926828;2442.4016116051594;2458.5697502937005;2475.8762061896923;2476.987615061931;2338.988305058472;2339.099446713011;2349.283468542094;2360.586897292967;2387.53443078598;2352.270796053229;2386.5942789725614;2393.6050551658955;2393.700787401575;2405.0620258099357;9780.364276322087;9881.654142104991;9892.95053524735;9893.406395616214;9908.405495670282;5561.694106824123;5715.657060576337;5724.570657200727;5725.055365864628;5729.942700573024;3555.839987200582;3589.8205089745406;3652.141746689526;3653.536717197137;3683.8158092095287;1110.74452875839;1119.5913491575516;1150.3385553306798;1157.1668398752884;1168.1472525056672;13705.520306789314;13725.245111518863;13726.245056521888;13739.793903091462;13769.586912392544;4764.617691154423;4790.353302304214;4801.615870730333;4840.515948405202;4911.18965370717;11555.768884622428;11575.826362604568;11583.305001699924;11587.304761714324;11596.188266821242;5500.80747173847;5561.193626924073;5585.329760428694;5597.104463285855;5601.495865372136;4366.868993930188;4953.083679519301;5052.873678158076;5078.415982162025;5103.897922041539;4320.65434765218;4324.113556720865;4420.84527041552;4436.401085853433;4457.751829487748;1294.4045738959996;1313.8817506424468;1317.7496275707585;1326.1247576599399;1330.5077121464926;912.6121398405651;977.6969139566758;1048.5019615682643;1049.8005378978123;1052.6578861869923;6525.641089740015;6534.607923524603;6557.409833115043;6559.967200164064;6678.165229346332;441.85860524631806;448.18206772640065;450.4189595421935;1026.6355443817338;1106.1095817866694;4415.249407600716;4423.314386270134;4442.777861106932;4461.04087621163;4481.148581769454;12088.879111208951;12525.624231273076;12565.811512827315;12587.685307867376;12591.433385497674;11096.223264371416;11557.075433965263;11577.826332605018;11842.526298947945;12486.313252771002;9095.863562046574;9221.400608960395;9261.814763704688;9266.675666351646;9267.675631353006;6614.437772789367;6795.218549866631;6874.8625027500175;6885.345892140239;6933.479989000744;11152.386618736047;11241.100711942861;11322.207445479067;11478.598249061242;11672.883271167519;11523;11594.01450876685;11627.825582616098;11642.5342986283;11771.23486973344;8237.752867413987;8384.706535271236;8544.145585441405;8565.743027709179;8603.569821508774;9355.578998944928;9433.386829856037;9444.716658500256;9537.046295370408;9537.47543885093;7851.332636725942;7963.562004089599;8057.717979870677;8092.433529652868;8131.105578386491;7957.562334071682;8135.064467586067;8144.144864789112;8162.591870406337;8199.467034642968;7233.927660723325;7237.203907570269;7273.636318184175;7390.593517356597;7460;5529.419410961791;5561.694106824001;5605.467480589337;5619.719014049363;5806.622569532967;5578.30271216098;5616.466435688603;5770.221020162225;5790.855228619318;5936.1095835625 16:51:02 INFO - PROCESS | 20577 | |4;prototype.html;321.1121249744456;329.92576670249196;329.9851506682199;332.58925227344224;333.1870236622642;5807.838432313539;5826.563007774413;5868.589198756089;5882.794102206423;5883.88232235355;5700.7719691212405;5713.971430142843;5743.942560574393;5750.884982300351;5759.769609215637;4218.810153543091;4337.371081193225;4350.630196433307;4366.956330436703;4367.585079417466;4334.2662934826;4336.392904993303;4341.240282950487;4348.347747837818;4362.302031674933;4336.132773445308;4338.826446942118;4347.586979236968;4362.367456718774;4381.430414046185;4092.0792821615046;4276.711959101896;4292.205941900758;4296.820352938834;4362.19299429605;3706.425504046877;3711.0722319420147;3721.292954338694;3735.906602334936;3753.7612587845947;5494.2857428534435;5499.477549632778;5502.58730595207;5527.811520523067;5544.556435485151;4952.356193694796;5009.44896061433;5072.72100034498;5083.288339632454;5091.159958606825;5993.730282137314;5995.280566332024;6029.6382217066675;6040.154378387029;6071.544634152457;4212.873613791591;4226.957730422687;4258.616724494779;4261.914761704748;4263.87208383749;6051.425114614105;6253.656048917308;6253.6873156342;6277.305127615518;6283.560150789446;4259.7231179973205;4262.595053469915;4285.828566857291;4290.764007979528;4302.182585308781;4340.262155433584;4349.804258808361;4352.194843953878;4362.8909277267685;4363.432753741993;4781.880452988703;4970.850874473772;4981.277714731344;5008.499150084962;5059.215821547666;5993.101034844721;6003.669798161056;6065.575409721366;6082.391760823883;6084.178635884187;1483.3325003748357;1490.225082956878;1497.8502149785152;1513.6518600721895;1521.3382178752279;943.1700103908654;963.8795150606175;986.2898712926682;994.1996692662332;995.0845222395413;5872.765089396376;5947.107933809876;5949.583529153005;5963.880722385528;5970.462658360769;6186.752529898844;6226.377362263737;6238.189035425366;6245.469135123565;6258.3428739982555;5723.399043100415;5884.734377264784;5901.940980590225;5930.703464826742;5934.732937017759;3304.487804390323;3328.9334213315597;3330.7002369786837;3352.228987332927;3366.9326613467592;6248.969085047378;6344.460720838688;6429.3570642936265;6432.485401167895;6458.6770661466735;6318.273398559132;6327;6331.05034244867;6346.904796428057;6361.363863613602;6209.565330426917;6346.238451385863;6423.42189202974;6455.160829092188;6473.643949582818;6175.96912015437;6211.223597050369;6215.160953271251;6252.9374706254175;6272.247330320391;6134.14122022926;6147.44672979434;6159.907601385893;6164.106204600218;6180.351063138306;6182;6571.4557079087335;6585.374389432996;6604.966975165094;6608.768693095719;4230.8519201827785;4309.5044069932355;4323.891902702332;4325.594181890961;4326.502452218036;6262.46769024638;6277.497800176066;6323.20959880015;6357.8383349661335;6457.289698133108;6081.483073938764;6092.482139018233;6102.847428814315;6125.84685382869;6161.476274516716;5900.970495147497;6109.694515274307;6116.755329786759;6124.693765311627;6134.2332208473945;5882.647041177543;5975.25309336333;5983.34183239852;5999.040153575407;6019;6162.661053642093;6167.506599471942;6187.628742275478;6193.318734939063;6204.534659900435;5980.461758441761;6064.241969753781;6095.695215239309;6102.115193297036;6104.511639068953 16:51:02 INFO - PROCESS | 20577 | |5;yui.html;724.0008787872735;792.5561685456145;891.7770557360661;891.933105017123;929.4098247612768;13641.590752277436;13694.383752731126;13704.10923289988;13710.657233569165;14464.059836110659;15134.772978405334;15361.615959601015;15444;15511.146886921215;15579.532614021597;817.3175398542195;860.5826174305441;865.4980111535294;867.7353407210799;868.1145231863495;853.0445900591346;853.4623187391966;854.7393045121236;856.1353033436212;858.1504310732362;896.238197532099;896.9551522423885;897.685809966513;898.5956319656149;902.4495058014605;886.9822603547925;887.5163036145252;889.799795046116;890.528020149322;892.2148509311829;652.6540933305356;658.9571677840925;659.9967000164968;660.3297652882328;663.9004149377583;15391.923040384838;15561.221938903009;15572.37710491579;15583.45457908979;15586.766198507032;14444.422223111169;14650.853491465055;14797.704045919021;14805.259737013213;14842.703145937021;11707.531698732126;11789.587364442203;11831.230969986924;11834.112441566916;12013.159078864483;3977.880663580097;4006;4028.234635419261;4032.5160980682213;4035.1324465240114;88474;89680.10319896783;90197.54901225517;90225.54887225588;90554.09445905522;10740.30188037775;10762.3542587445;10802.886335626019;10897.237193396386;11200.271982321123;21082.578348432944;21129.26047588327;21135.8943205283;21203;21226.150953961667;8064.395170362246;8113.066997295268;8325.542095184703;8521.318294536422;8588.055313915464;5072.644914855925;5159.380874295109;5243.292155559027;5264.394594621667;5313.601479889024;3551.271989242199;3661.9450708239397;3662.61542538035;3663.8900832975055;3671.394219953731;550.4632982841697;604.039577072457;613.2885852411182;613.7913109542777;614.4807637546267;734.6473692627569;737.6053811210976;740.526063319476;758.5398108640844;780.7306479264645;10277.434741089312;10439.37363758177;10517.9474102629;10571.260011799253;10642.201834862417;752.2176935986622;836.4354061008795;841.066416277931;847.0766864118141;850.974470765878;4927.778249978688;4983.103041452573;4991.875203119951;5002.124628190125;5015.84952451427;126112;128879;131781;134725;135718;108274.45862770636;109456;110392;110707;114359;66319.66840165865;101029;102147.48926255321;102406;103752.48123759333;9191.724248272563;9235.630574776933;9509.76225594338;9512.923159859814;9701.126898579161;56742.14886776619;57569.272921812415;58867.70566147142;59677.70161149166;60981.39018609757;78912;88172.5591372039;88480;88746.55626721826;90971;11706.590269340531;11717.238379505305;12059.57791477294;12059.81910271329;12170.330631815335;12914.870851291367;13171.736565268833;13273;13366.064375493623;13383.799243011557;12374.13381063317;12540.937295313464;12553.184043037174;12666.683332916751;13008.479660813462;12086.697832554257;12111.152219344562;12114.15200935926;12717.682057948256;12874.227546347249;12306.200096993667;12520.749585008067;12624.242545447303;12674.302913339854;13188.868111318763;10608;10689.839652405375;10799.514021869192;10893.836592451278;10961.780764384826;10650.254482186483;10869.782604348027;10881.564737410732;10916.836247456453;11054.723631908944 16:51:02 INFO - PROCESS | 20577 | __end_tp_report 16:51:02 INFO - PROCESS | 20577 | __start_cc_report 16:51:02 INFO - PROCESS | 20577 | _x_x_mozilla_cycle_collect,334 16:51:02 INFO - PROCESS | 20577 | __end_cc_report 16:51:02 INFO - PROCESS | 20577 | __startTimestamp1470095462706__endTimestamp 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | ------- Summary: start ------- 16:51:02 INFO - PROCESS | 20577 | Number of tests: 6 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | [#0] dojo.html Cycles:165 Average:5573.04 Median:5841.09 stddev:620.10 (10.6%) stddev-sans-first:597.08 16:51:02 INFO - PROCESS | 20577 | Values: 3354.4 3359.8 3374.1 3387.8 3399.7 5515.9 5555.2 5568.1 5595.3 5606.9 5567.7 5580.2 5631.5 5645.7 5649.3 4988.8 5014.2 5070.2 5096.4 5102.6 4951.1 5011.6 5046.1 5088.3 5129.0 5008.5 5013.4 5038.2 5041.1 5080.2 5022.4 5084.6 5085.0 5087.3 5098.3 4817.6 4999.4 5012.6 5037.0 5065.6 5331.1 5369.7 5397.6 5439.2 5448.1 5954.3 5983.9 5997.1 6002.8 6035.0 6052.3 6057.1 6058.0 6064.1 6066.5 5499.5 5559.9 5603.2 5608.0 5629.5 6119.5 6119.6 6153.4 6160.8 6170.2 5864.3 5865.9 5885.7 5894.3 5924.8 5867.3 5876.9 5894.0 5937.1 5995.3 5922.1 5944.7 5947.9 5956.6 5958.9 5792.1 5950.8 5970.9 5978.1 5986.8 4612.6 4623.9 4642.6 4671.8 4683.6 4160.0 4181.2 4186.9 4187.0 4210.7 5673.5 5682.9 5855.6 5872.6 5874.4 5871.1 5955.6 5962.7 6054.7 6111.3 5832.8 5837.0 5840.0 5841.1 5853.7 5080.1 5152.4 5223.7 5226.8 5253.7 6169.0 6293.5 6315.0 6316.9 6322.9 6064.7 6080.9 6085.0 6088.0 6089.5 6027.6 6062.2 6066.8 6257.4 6337.4 5813.4 5846.4 5866.2 5921.2 5941.3 6039.8 6039.9 6042.6 6047.3 6056.3 5707.8 5760.5 5883.4 5896.2 5912.3 5778.9 5814.9 5858.8 5881.4 5935.4 5776.2 5781.7 5782.8 5791.0 5796.7 5739.5 5845.7 5862.4 5912.6 5917.7 5871.5 5881.3 5882.1 5890.2 5907.0 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | [#1] ext.html Cycles:180 Average:70308.45 Median:44364.67 stddev:154485.27 (348.2%) stddev-sans-first:154829.94 16:51:02 INFO - PROCESS | 20577 | Values: 581.9 650.2 668.5 670.9 676.0 40198.6 44217.3 44484.8 44593.6 44626.3 44777.8 45418.5 45596.5 45688.5 45831.8 8798.0 8989.1 8990.8 9019.3 9036.8 8880.0 8930.7 8940.8 8978.1 9011.7 9006.1 9025.3 9033.3 9047.3 9064.5 8947.7 8967.0 9004.6 9006.3 9049.5 8835.1 8904.4 8917.6 8961.5 8961.9 43148.1 43477.8 43490.0 43571.8 43611.0 3818.0 3826.1 3887.2 3949.5 4295.2 34065.8 34883.8 34976.1 35094.8 35205.0 4717.1 4788.4 4815.4 4848.5 4895.4 368291.0 369011.2 369458.0 369544.0 370611.0 62894.4 63038.0 63279.4 63347.4 63578.0 40079.8 40479.6 40517.4 42152.6 42166.6 15571.3 15796.6 15809.1 15935.6 15950.9 10903.1 10904.8 10912.6 10931.3 10942.1 20672.7 20782.0 20833.9 20885.6 21048.9 7501.6 7574.1 7638.6 7662.7 7838.7 1065.7 1103.3 1162.0 1182.9 1188.2 32934.2 33103.2 33178.0 33434.7 33754.3 1161.7 1195.5 1239.9 1248.7 1251.9 17268.4 17423.7 17444.9 17540.5 17551.8 582198.0 813621.0 830008.0 831340.0 837317.0 62116.0 62443.4 62837.7 62918.0 62951.4 1425.9 1449.4 1474.8 1488.3 1522.8 33307.3 33428.8 33504.5 34060.0 34453.5 53963.7 58230.4 58883.7 59072.7 63621.0 487612.0 489238.0 493882.0 494978.0 495625.0 46657.1 47333.8 47790.5 47879.3 47883.5 51163.5 51417.5 51886.0 52693.0 53085.0 43484.6 43900.6 44364.6 44470.1 45247.1 43403.3 43916.3 44364.8 44764.3 44803.6 43375.8 43509.8 43830.8 43971.8 46052.0 37241.3 37824.9 37949.2 38118.0 38439.8 29284.3 29365.0 29475.3 29523.1 29747.7 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | [#2] jquery.html Cycles:180 Average:28637.36 Median:20461.37 stddev:81915.53 (400.3%) stddev-sans-first:82117.90 16:51:02 INFO - PROCESS | 20577 | Values: 409.5 411.8 415.8 416.3 426.3 18272.7 19245.1 19939.1 19940.6 20066.6 12396.1 12471.5 12732.4 13001.7 13084.0 5228.6 5376.1 5399.8 5415.8 5476.4 5755.1 5788.5 5812.2 5840.3 5845.4 5644.9 5747.3 5759.1 5766.5 5807.7 5694.9 5733.1 5744.5 5759.5 5773.9 5047.4 5188.1 5198.4 5253.6 5258.0 22500.2 23274.8 23332.0 23383.4 23657.3 792.0 2324.6 2525.9 2646.0 2881.7 10441.5 10969.2 11137.5 12268.4 15172.4 2000.1 2000.7 2016.8 2052.4 2087.9 50173.0 69420.7 82314.0 84918.6 85673.0 11395.0 11725.3 11764.8 11815.7 11952.6 16267.0 16493.3 16515.3 16595.3 16693.0 8115.8 8148.0 8265.3 8313.3 8362.4 7590.5 7681.1 7721.1 7747.9 7750.2 4926.3 4976.8 5065.0 5147.0 5163.3 2029.9 2079.6 2081.9 2103.3 2123.9 977.6 987.7 1031.7 1092.6 1093.4 18467.6 19364.7 19365.8 19393.3 19445.4 982.4 1025.1 1040.2 1084.6 1104.1 8815.4 10584.5 10747.0 10757.2 10766.1 488107.6 490966.0 497788.0 504009.0 507407.0 86216.0 88553.6 89890.6 90958.5 91507.5 473.9 488.5 492.4 492.4 495.6 11145.4 11413.5 11561.8 11747.8 11962.1 40234.8 40513.2 41330.2 42527.8 43250.8 52240.5 52344.5 54778.0 59404.1 60591.7 8771.7 8828.6 9007.4 9107.2 9241.5 12412.7 12581.9 12602.6 12988.9 13098.5 11100.8 11785.8 11829.8 11992.3 12237.6 11472.8 11562.7 11574.5 11865.0 11881.2 11508.8 11734.9 11857.1 12011.1 12223.4 8296.0 8554.7 9113.3 9299.9 9356.1 8619.1 11104.9 11646.1 11650.8 11667.3 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | [#3] mootools.html Cycles:180 Average:6345.23 Median:4450.26 stddev:3731.45 (83.8%) stddev-sans-first:3724.90 16:51:02 INFO - PROCESS | 20577 | Values: 1602.0 1624.6 1818.8 1867.1 1891.7 8330.5 9322.3 9398.2 10029.9 10087.9 9542.9 9788.9 9897.5 9918.0 9921.7 2402.4 2432.0 2445.5 2445.6 2457.4 2395.5 2406.7 2439.9 2448.5 2453.5 2435.0 2442.4 2458.6 2475.9 2477.0 2339.0 2339.1 2349.3 2360.6 2387.5 2352.3 2386.6 2393.6 2393.7 2405.1 9780.4 9881.7 9893.0 9893.4 9908.4 5561.7 5715.7 5724.6 5725.1 5729.9 3555.8 3589.8 3652.1 3653.5 3683.8 1110.7 1119.6 1150.3 1157.2 1168.1 13705.5 13725.2 13726.2 13739.8 13769.6 4764.6 4790.4 4801.6 4840.5 4911.2 11555.8 11575.8 11583.3 11587.3 11596.2 5500.8 5561.2 5585.3 5597.1 5601.5 4366.9 4953.1 5052.9 5078.4 5103.9 4320.7 4324.1 4420.8 4436.4 4457.8 1294.4 1313.9 1317.7 1326.1 1330.5 912.6 977.7 1048.5 1049.8 1052.7 6525.6 6534.6 6557.4 6560.0 6678.2 441.9 448.2 450.4 1026.6 1106.1 4415.2 4423.3 4442.8 4461.0 4481.1 12088.9 12525.6 12565.8 12587.7 12591.4 11096.2 11557.1 11577.8 11842.5 12486.3 9095.9 9221.4 9261.8 9266.7 9267.7 6614.4 6795.2 6874.9 6885.3 6933.5 11152.4 11241.1 11322.2 11478.6 11672.9 11523.0 11594.0 11627.8 11642.5 11771.2 8237.8 8384.7 8544.1 8565.7 8603.6 9355.6 9433.4 9444.7 9537.0 9537.5 7851.3 7963.6 8057.7 8092.4 8131.1 7957.6 8135.1 8144.1 8162.6 8199.5 7233.9 7237.2 7273.6 7390.6 7460.0 5529.4 5561.7 5605.5 5619.7 5806.6 5578.3 5616.5 5770.2 5790.9 5936.1 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | [#4] prototype.html Cycles:180 Average:5070.35 Median:5916.32 stddev:1542.19 (26.1%) stddev-sans-first:1504.76 16:51:02 INFO - PROCESS | 20577 | Values: 321.1 329.9 330.0 332.6 333.2 5807.8 5826.6 5868.6 5882.8 5883.9 5700.8 5714.0 5743.9 5750.9 5759.8 4218.8 4337.4 4350.6 4367.0 4367.6 4334.3 4336.4 4341.2 4348.3 4362.3 4336.1 4338.8 4347.6 4362.4 4381.4 4092.1 4276.7 4292.2 4296.8 4362.2 3706.4 3711.1 3721.3 3735.9 3753.8 5494.3 5499.5 5502.6 5527.8 5544.6 4952.4 5009.4 5072.7 5083.3 5091.2 5993.7 5995.3 6029.6 6040.2 6071.5 4212.9 4227.0 4258.6 4261.9 4263.9 6051.4 6253.7 6253.7 6277.3 6283.6 4259.7 4262.6 4285.8 4290.8 4302.2 4340.3 4349.8 4352.2 4362.9 4363.4 4781.9 4970.9 4981.3 5008.5 5059.2 5993.1 6003.7 6065.6 6082.4 6084.2 1483.3 1490.2 1497.9 1513.7 1521.3 943.2 963.9 986.3 994.2 995.1 5872.8 5947.1 5949.6 5963.9 5970.5 6186.8 6226.4 6238.2 6245.5 6258.3 5723.4 5884.7 5901.9 5930.7 5934.7 3304.5 3328.9 3330.7 3352.2 3366.9 6249.0 6344.5 6429.4 6432.5 6458.7 6318.3 6327.0 6331.1 6346.9 6361.4 6209.6 6346.2 6423.4 6455.2 6473.6 6176.0 6211.2 6215.2 6252.9 6272.2 6134.1 6147.4 6159.9 6164.1 6180.4 6182.0 6571.5 6585.4 6605.0 6608.8 4230.9 4309.5 4323.9 4325.6 4326.5 6262.5 6277.5 6323.2 6357.8 6457.3 6081.5 6092.5 6102.8 6125.8 6161.5 5901.0 6109.7 6116.8 6124.7 6134.2 5882.6 5975.3 5983.3 5999.0 6019.0 6162.7 6167.5 6187.6 6193.3 6204.5 5980.5 6064.2 6095.7 6102.1 6104.5 16:51:02 INFO - PROCESS | 20577 | 16:51:02 INFO - PROCESS | 20577 | [#5] yui.html Cycles:180 Average:22611.28 Median:3662.28 stddev:34448.20 (940.6%) stddev-sans-first:34505.64 16:51:02 INFO - PROCESS | 20577 | Values: 724.0 792.6 891.8 891.9 929.4 13641.6 13694.4 13704.1 13710.7 14464.1 15134.8 15361.6 15444.0 15511.1 15579.5 817.3 860.6 865.5 867.7 868.1 853.0 853.5 854.7 856.1 858.2 896.2 897.0 897.7 898.6 902.4 887.0 887.5 889.8 890.5 892.2 652.7 659.0 660.0 660.3 663.9 15391.9 15561.2 15572.4 15583.5 15586.8 14444.4 14650.9 14797.7 14805.3 14842.7 11707.5 11789.6 11831.2 11834.1 12013.2 3977.9 4006.0 4028.2 4032.5 4035.1 88474.0 89680.1 90197.5 90225.5 90554.1 10740.3 10762.4 10802.9 10897.2 11200.3 21082.6 21129.3 21135.9 21203.0 21226.2 8064.4 8113.1 8325.5 8521.3 8588.1 5072.6 5159.4 5243.3 5264.4 5313.6 3551.3 3661.9 3662.6 3663.9 3671.4 550.5 604.0 613.3 613.8 614.5 734.6 737.6 740.5 758.5 780.7 10277.4 10439.4 10517.9 10571.3 10642.2 752.2 836.4 841.1 847.1 851.0 4927.8 4983.1 4991.9 5002.1 5015.8 126112.0 128879.0 131781.0 134725.0 135718.0 108274.5 109456.0 110392.0 110707.0 114359.0 66319.7 101029.0 102147.5 102406.0 103752.5 9191.7 9235.6 9509.8 9512.9 9701.1 56742.1 57569.3 58867.7 59677.7 60981.4 78912.0 88172.6 88480.0 88746.6 90971.0 11706.6 11717.2 12059.6 12059.8 12170.3 12914.9 13171.7 13273.0 13366.1 13383.8 12374.1 12540.9 12553.2 12666.7 13008.5 12086.7 12111.2 12114.2 12717.7 12874.2 12306.2 12520.7 12624.2 12674.3 13188.9 10608.0 10689.8 10799.5 10893.8 10961.8 10650.3 10869.8 10881.6 10916.8 11054.7 16:51:02 INFO - PROCESS | 20577 | -------- Summary: end -------- 16:51:02 INFO - PROCESS | 20577 | 16:51:03 INFO - TEST-INFO | 20577: exit 0 16:51:03 INFO - TEST-OK | dromaeo_css | took 1109357ms 16:51:03 INFO - TEST-START | kraken 16:51:03 INFO - operating with platform_type : linux_ 16:51:03 INFO - Initialising browser for kraken test... 16:51:03 INFO - TEST-INFO | started process 20822 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTI5JST/profile http://localhost:39396/getInfo.html) 16:51:07 INFO - PROCESS | 20822 | __metrics Screen width/height:1600/1200 16:51:07 INFO - PROCESS | 20822 | colorDepth:24 16:51:07 INFO - PROCESS | 20822 | Browser inner width/height: 1024/697 16:51:07 INFO - PROCESS | 20822 | __metrics 16:51:08 ERROR - PROCESS | 20822 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2947: TypeError: this.tabs is undefined 16:51:09 INFO - TEST-INFO | 20822: exit 0 16:51:09 INFO - Browser initialized. 16:51:09 INFO - Running cycle 1/1 for kraken test... 16:51:09 INFO - TEST-INFO | started process 20950 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTI5JST/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:51:20 INFO - PROCESS | 20950 | RSS: Main: 152870912 16:51:20 INFO - PROCESS | 20950 | 16:51:23 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:39396/tests/kraken/driver.html?testName=audio-beat-detection) 16:51:24 INFO - PROCESS | 20950 | RSS: Main: 158179328 16:51:24 INFO - PROCESS | 20950 | 16:51:26 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:39396/tests/kraken/driver.html?testName=audio-dft) 16:51:27 INFO - PROCESS | 20950 | RSS: Main: 157425664 16:51:27 INFO - PROCESS | 20950 | 16:51:30 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:39396/tests/kraken/driver.html?testName=audio-fft) 16:51:30 INFO - PROCESS | 20950 | RSS: Main: 158056448 16:51:30 INFO - PROCESS | 20950 | 16:51:32 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:39396/tests/kraken/driver.html?testName=audio-oscillator) 16:51:33 INFO - PROCESS | 20950 | RSS: Main: 157937664 16:51:33 INFO - PROCESS | 20950 | 16:51:35 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:39396/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:51:36 INFO - PROCESS | 20950 | RSS: Main: 157519872 16:51:36 INFO - PROCESS | 20950 | 16:51:39 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:39396/tests/kraken/driver.html?testName=imaging-darkroom) 16:51:39 INFO - PROCESS | 20950 | RSS: Main: 156647424 16:51:39 INFO - PROCESS | 20950 | 16:51:43 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:39396/tests/kraken/driver.html?testName=imaging-desaturate) 16:51:43 INFO - PROCESS | 20950 | RSS: Main: 158105600 16:51:43 INFO - PROCESS | 20950 | 16:51:46 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:39396/tests/kraken/driver.html?testName=json-parse-financial) 16:51:47 INFO - PROCESS | 20950 | RSS: Main: 157822976 16:51:47 INFO - PROCESS | 20950 | 16:51:48 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:39396/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:51:49 INFO - PROCESS | 20950 | RSS: Main: 157753344 16:51:49 INFO - PROCESS | 20950 | 16:51:51 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:51:51 INFO - PROCESS | 20950 | RSS: Main: 158167040 16:51:51 INFO - PROCESS | 20950 | 16:51:53 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:51:54 INFO - PROCESS | 20950 | RSS: Main: 158097408 16:51:54 INFO - PROCESS | 20950 | 16:51:57 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:51:57 INFO - PROCESS | 20950 | RSS: Main: 158707712 16:51:57 INFO - PROCESS | 20950 | 16:52:00 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:52:01 INFO - PROCESS | 20950 | RSS: Main: 158494720 16:52:01 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | Cycle 1(1): loaded http://localhost:39396/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:39396/tests/kraken/driver.html?testName=ai-astar) 16:52:02 INFO - PROCESS | 20950 | RSS: Main: 159096832 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | __start_tp_report 16:52:02 INFO - PROCESS | 20950 | _x_x_mozilla_page_load 16:52:02 INFO - PROCESS | 20950 | _x_x_mozilla_page_load_details 16:52:02 INFO - PROCESS | 20950 | |i|pagename|runs| 16:52:02 INFO - PROCESS | 20950 | |0;ai-astar;110;105;112;110;111;111;112;114;111;110 16:52:02 INFO - PROCESS | 20950 | |1;audio-beat-detection;151;143;152;176;131;152;153;136;182;130 16:52:02 INFO - PROCESS | 20950 | |2;audio-dft;162;161;161;162;163;162;161;163;163;162 16:52:02 INFO - PROCESS | 20950 | |3;audio-fft;73;74;75;168;74;75;83;77;168;77 16:52:02 INFO - PROCESS | 20950 | |4;audio-oscillator;89;83;142;84;84;83;142;84;83;153 16:52:02 INFO - PROCESS | 20950 | |5;imaging-gaussian-blur;117;117;117;117;117;117;117;117;117;118 16:52:02 INFO - PROCESS | 20950 | |6;imaging-darkroom;148;146;146;146;145;146;148;146;146;146 16:52:02 INFO - PROCESS | 20950 | |7;imaging-desaturate;96;95;95;96;95;95;94;95;95;95 16:52:02 INFO - PROCESS | 20950 | |8;json-parse-financial;52;55;52;53;62;53;52;53;54;52 16:52:02 INFO - PROCESS | 20950 | |9;json-stringify-tinderbox;59;59;60;60;61;60;60;60;60;61 16:52:02 INFO - PROCESS | 20950 | |10;stanford-crypto-aes;80;76;81;77;78;86;78;77;102;77 16:52:02 INFO - PROCESS | 20950 | |11;stanford-crypto-ccm;120;123;138;123;131;119;118;127;142;122 16:52:02 INFO - PROCESS | 20950 | |12;stanford-crypto-pbkdf2;157;155;162;153;153;160;153;154;154;156 16:52:02 INFO - PROCESS | 20950 | |13;stanford-crypto-sha256-iterative;62;63;64;63;66;62;60;59;61;69 16:52:02 INFO - PROCESS | 20950 | __end_tp_report 16:52:02 INFO - PROCESS | 20950 | __start_cc_report 16:52:02 INFO - PROCESS | 20950 | _x_x_mozilla_cycle_collect,863 16:52:02 INFO - PROCESS | 20950 | __end_cc_report 16:52:02 INFO - PROCESS | 20950 | __startTimestamp1470095522912__endTimestamp 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | ------- Summary: start ------- 16:52:02 INFO - PROCESS | 20950 | Number of tests: 14 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#0] ai-astar Cycles:10 Average:110.60 Median:111.00 stddev:2.32 (2.1%) stddev-sans-first:2.45 16:52:02 INFO - PROCESS | 20950 | Values: 110.0 105.0 112.0 110.0 111.0 111.0 112.0 114.0 111.0 110.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#1] audio-beat-detection Cycles:10 Average:150.60 Median:152.00 stddev:17.39 (11.4%) stddev-sans-first:18.44 16:52:02 INFO - PROCESS | 20950 | Values: 151.0 143.0 152.0 176.0 131.0 152.0 153.0 136.0 182.0 130.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#2] audio-dft Cycles:10 Average:162.00 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.87 16:52:02 INFO - PROCESS | 20950 | Values: 162.0 161.0 161.0 162.0 163.0 162.0 161.0 163.0 163.0 162.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#3] audio-fft Cycles:10 Average:94.40 Median:75.00 stddev:38.89 (51.9%) stddev-sans-first:40.47 16:52:02 INFO - PROCESS | 20950 | Values: 73.0 74.0 75.0 168.0 74.0 75.0 83.0 77.0 168.0 77.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#4] audio-oscillator Cycles:10 Average:102.70 Median:83.50 stddev:29.85 (35.8%) stddev-sans-first:31.25 16:52:02 INFO - PROCESS | 20950 | Values: 89.0 83.0 142.0 84.0 84.0 83.0 142.0 84.0 83.0 153.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#5] imaging-gaussian-blur Cycles:10 Average:117.10 Median:117.00 stddev:0.32 (0.3%) stddev-sans-first:0.33 16:52:02 INFO - PROCESS | 20950 | Values: 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 117.0 118.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#6] imaging-darkroom Cycles:10 Average:146.30 Median:146.00 stddev:0.95 (0.6%) stddev-sans-first:0.78 16:52:02 INFO - PROCESS | 20950 | Values: 148.0 146.0 146.0 146.0 145.0 146.0 148.0 146.0 146.0 146.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#7] imaging-desaturate Cycles:10 Average:95.10 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 16:52:02 INFO - PROCESS | 20950 | Values: 96.0 95.0 95.0 96.0 95.0 95.0 94.0 95.0 95.0 95.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#8] json-parse-financial Cycles:10 Average:53.80 Median:53.00 stddev:3.05 (5.8%) stddev-sans-first:3.16 16:52:02 INFO - PROCESS | 20950 | Values: 52.0 55.0 52.0 53.0 62.0 53.0 52.0 53.0 54.0 52.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#9] json-stringify-tinderbox Cycles:10 Average:60.00 Median:60.00 stddev:0.67 (1.1%) stddev-sans-first:0.60 16:52:02 INFO - PROCESS | 20950 | Values: 59.0 59.0 60.0 60.0 61.0 60.0 60.0 60.0 60.0 61.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#10] stanford-crypto-aes Cycles:10 Average:81.20 Median:78.00 stddev:7.87 (10.1%) stddev-sans-first:8.34 16:52:02 INFO - PROCESS | 20950 | Values: 80.0 76.0 81.0 77.0 78.0 86.0 78.0 77.0 102.0 77.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#11] stanford-crypto-ccm Cycles:10 Average:126.30 Median:125.00 stddev:8.22 (6.6%) stddev-sans-first:8.40 16:52:02 INFO - PROCESS | 20950 | Values: 120.0 123.0 138.0 123.0 131.0 119.0 118.0 127.0 142.0 122.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:155.70 Median:155.50 stddev:3.13 (2.0%) stddev-sans-first:3.28 16:52:02 INFO - PROCESS | 20950 | Values: 157.0 155.0 162.0 153.0 153.0 160.0 153.0 154.0 154.0 156.0 16:52:02 INFO - PROCESS | 20950 | 16:52:02 INFO - PROCESS | 20950 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:62.90 Median:63.00 stddev:2.92 (4.6%) stddev-sans-first:3.08 16:52:02 INFO - PROCESS | 20950 | Values: 62.0 63.0 64.0 63.0 66.0 62.0 60.0 59.0 61.0 69.0 16:52:02 INFO - PROCESS | 20950 | -------- Summary: end -------- 16:52:02 INFO - PROCESS | 20950 | 16:52:03 INFO - TEST-INFO | 20950: exit 0 16:52:03 INFO - TEST-OK | kraken | took 60391ms 16:52:03 INFO - SUITE-END | took 1169s 16:52:04 INFO - Completed test suite (00:19:29) 16:52:04 INFO - javascript benchmark 16:52:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7555.885751020601, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3354.3962086824367, 3359.7648164628454, 3374.072130164205, 3387.8306084695773, 3399.711024562912, 5515.882297322176, 5555.194496797967, 5568.0812665910125, 5595.300587426572, 5606.915896261559, 5567.6659400436, 5580.218769372291, 5631.549476041917, 5645.745941432635, 5649.265595472582, 4988.800447982077, 5014.24786282057, 5070.2141168119, 5096.439391666932, 5102.591792656597, 4951.108800415924, 5011.574016208627, 5046.116929537335, 5088.313077734514, 5128.974355128238, 5008.524190201925, 5013.4234563025175, 5038.168702164138, 5041.067402530525, 5080.187170052793, 5022.447530771612, 5084.567811736007, 5084.9745751271375, 5087.338645976037, 5098.337216161875, 4817.56641902227, 4999.4250661174065, 5012.649114561982, 5037.0, 5065.594752419835, 5331.067063263913, 5369.677819330814, 5397.595180361489, 5439.211314359396, 5448.128299472065, 5954.315253745788, 5983.880322393571, 5997.100434934795, 6002.759889604411, 6035.0, 6052.334243233285, 6057.121717350959, 6058.0, 6064.090386442069, 6066.545009124334, 5499.532539734128, 5559.861003474945, 5603.187537806995, 5608.018596745594, 5629.4652008059165, 6119.5104391648565, 6119.602225855394, 6153.353897840751, 6160.815175544741, 6170.228721409824, 5864.296284445893, 5865.94134058654, 5885.676287804126, 5894.2926848778425, 5924.7926322578505, 5867.266591676041, 5876.882462350729, 5893.970530147408, 5937.079752638348, 5995.250593675791, 5922.082077278029, 5944.6730429826775, 5947.910781338283, 5956.583039187215, 5958.910616340759, 5792.102224155263, 5950.761969521257, 5970.85072873173, 5978.133170690226, 5986.820395388146, 4612.607928326062, 4623.861284161413, 4642.582167604932, 4671.8364857229835, 4683.601893838993, 4159.958400415957, 4181.233393930921, 4186.874393768131, 4186.995121170897, 4210.7473551587, 5673.489385955284, 5682.886342273214, 5855.560832937462, 5872.618279811799, 5874.441928016831, 5871.089981052944, 5955.553333500005, 5962.731677074542, 6054.727537260834, 6111.297200821873, 5832.825015249549, 5836.970815145982, 5840.036393994943, 5841.094630332305, 5853.707314634251, 5080.085584594808, 5152.433232344439, 5223.712695801767, 5226.790928362899, 5253.711045892436, 6168.9691551541955, 6293.527985401113, 6314.968425157845, 6316.905246421307, 6322.9051564226575, 6064.66644334557, 6080.8783824324155, 6085.0, 6088.025915853442, 6089.512838972783, 6027.60820546663, 6062.211912451353, 6066.787662431793, 6257.374262573706, 6337.429631333202, 5813.418658134153, 5846.386129456516, 5866.208061911588, 5921.171036055042, 5941.287045554561, 6039.788607398896, 6039.939600604113, 6042.5770196087615, 6047.274327080602, 6056.333803281724, 5707.828765137053, 5760.452756988079, 5883.441073098049, 5896.233489646319, 5912.349641539341, 5778.855528611818, 5814.912776308443, 5858.765649373977, 5881.411858814085, 5935.40645935403, 5776.249087618583, 5781.739821707949, 5782.768689252551, 5790.986577349031, 5796.652200867961, 5739.454751798572, 5845.736941837543, 5862.355140934579, 5912.586118971631, 5917.733701983507, 5871.530277577698, 5881.32364778056, 5882.058870580858, 5890.175375447527, 5906.970465147647], "value": 5532.996794709022, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [581.9476247137757, 650.2457149706341, 668.5119862500375, 670.9261981182069, 675.9831004224897, 40198.59801401985, 44217.33673994893, 44484.77757611215, 44593.55406445934, 44626.33060504091, 44777.77611111948, 45418.545814541845, 45596.54403455964, 45688.54311456893, 45831.77084114583, 8798.0, 8989.101089891024, 8990.775230619221, 9019.323550733721, 9036.819263614723, 8879.95560022199, 8930.732078037669, 8940.776480588005, 8978.147076027766, 9011.729648110535, 9006.099390060974, 9025.323100767437, 9033.277337812959, 9047.276217902552, 9064.501452420147, 8947.731568052935, 8967.0, 9004.639814407416, 9006.324525660602, 9049.457032578066, 8835.116488351177, 8904.421212621159, 8917.643294268222, 8961.46231226122, 8961.910380896174, 43148.137037259396, 43477.78261108674, 43490.0, 43571.78214108941, 43611.0, 3817.9882331182257, 3826.1391186982983, 3887.1836914248042, 3949.5260568731937, 4295.248331541999, 34065.82967085174, 34883.82558087219, 34976.12559686003, 35094.82452587746, 35205.0, 4717.056588682277, 4788.449328327218, 4815.422149342101, 4848.539388758098, 4895.363602731622, 368291.0, 369011.15494422626, 369458.0, 369544.0, 370611.0, 62894.37105628977, 63038.0, 63279.36720632735, 63347.366526334146, 63578.0, 40079.79960100181, 40479.59520404758, 40517.39223911644, 42152.57847421548, 42166.57833421627, 15571.299291531908, 15796.605084872741, 15809.13049782273, 15935.601609959844, 15950.920245398698, 10903.127749779998, 10904.836427453594, 10912.61805836792, 10931.344119352865, 10942.069924056545, 20672.689909651366, 20782.0, 20833.89583052075, 20885.582288354148, 21048.894755526126, 7501.624918754041, 7574.128975167926, 7638.618069096523, 7662.65518051689, 7838.686452541949, 1065.7442213868617, 1103.3214573037685, 1161.988380116205, 1182.8521434820648, 1188.2157775868059, 32934.176645584055, 33103.172420689676, 33178.0, 33434.66565334315, 33754.324913501594, 1161.7269941563725, 1195.54569263681, 1239.9442025108892, 1248.6940699528673, 1251.8622951475331, 17268.395606153725, 17423.651526969643, 17444.912775436296, 17540.473785786446, 17551.82448175502, 582198.0, 813621.0, 830008.0, 831340.0, 837317.0, 62116.0, 62443.375566244664, 62837.68581157065, 62918.0, 62951.37048629547, 1425.8645428684258, 1449.39125567262, 1474.800901878254, 1488.3302513868587, 1522.7715842623475, 33307.333853322314, 33428.832855835564, 33504.497432538046, 34060.0, 34453.483197752554, 53963.730181348845, 58230.4176958225, 58883.70558147182, 59072.70463647654, 63621.0, 487612.0, 489238.0, 493882.0, 494978.0, 495625.0, 46657.06685866332, 47333.76333118312, 47790.52209477861, 47879.281810773566, 47883.521164787904, 51163.488365115874, 51417.485825142765, 51886.0, 52693.0, 53085.0, 43484.56515434805, 43900.56099439092, 44364.556354437336, 44470.11059778851, 45247.09505809931, 43403.34894976641, 43916.34125488184, 44364.77817610891, 44764.32853507265, 44803.551964479935, 43375.78312108419, 43509.78245108754, 43830.780846096844, 43971.78014109909, 46052.0, 37241.25517489581, 37824.91962942065, 37949.241015180094, 38118.0, 38439.807800960814, 29284.267893302836, 29364.97222597199, 29475.263118422212, 29523.11430657084, 29747.70252297536], "value": 21392.269755959947, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.54407082820563, 411.78998710657555, 415.7505496701978, 416.3213961243173, 426.32214778502174, 18272.725909111374, 19245.133968971393, 19939.1027403767, 19940.60118797623, 20066.598668026632, 12396.070294727886, 12471.501139954413, 12732.427040783165, 13001.674958126052, 13084.0, 5228.581713462923, 5376.059189641816, 5399.78400863965, 5415.810446634368, 5476.4249753775875, 5755.107958266475, 5788.450097240755, 5812.186293918855, 5840.269966254218, 5845.444682755153, 5644.943550564483, 5747.339056008571, 5759.107338362561, 5766.538676905838, 5807.680577568253, 5694.914576281359, 5733.082706766897, 5744.511716504102, 5759.510441612468, 5773.9422605773825, 5047.369078865142, 5188.11801993662, 5198.40218374888, 5253.579713622901, 5257.973710131463, 22500.21249256268, 23274.76725232743, 23332.0, 23383.4154146146, 23657.29028129159, 791.9762407127795, 2324.5583339165673, 2525.939105575644, 2646.0474229277443, 2881.654201495813, 10441.47792610374, 10969.23215374924, 11137.498812553455, 12268.44791984363, 15172.393104275816, 2000.1099510717736, 2000.6998950157447, 2016.8487363447653, 2052.4253209101475, 2087.926922557703, 50173.0, 69420.65289673519, 82314.0, 84918.5754071238, 85673.0, 11395.0, 11725.296482210924, 11764.823527647091, 11815.704607384712, 11952.64142075739, 16267.0, 16493.34026638945, 16515.256813443182, 16595.253213605418, 16693.0, 8115.83768324642, 8147.959260203661, 8265.256126948603, 8313.293370063491, 8362.372822038249, 7590.468667193243, 7681.116671582839, 7721.112072111779, 7747.922520774833, 7750.186230445835, 4926.251180830754, 4976.751162441863, 5065.0, 5146.996335714574, 5163.251328557338, 2029.8985050747403, 2079.59806423581, 2081.937541873716, 2103.2638576498407, 2123.9044243009103, 977.5698692575244, 987.6641941739699, 1031.7073413389414, 1092.6339676208559, 1093.4423444043596, 18467.630647386977, 19364.70952935707, 19365.80634193668, 19393.32123375675, 19445.416637500897, 982.3958265666564, 1025.108155904353, 1040.2250323509033, 1084.5770149641646, 1104.100158370925, 8815.426997245288, 10584.470776461147, 10747.0, 10757.193210509087, 10766.084882784895, 488107.55946220044, 490966.0, 497788.0, 504009.0, 507407.0, 86216.0, 88553.55723221472, 89890.55054724685, 90958.54520727486, 91507.54246228727, 473.91469535483253, 488.5310102301759, 492.36730800920645, 492.36976669862634, 495.61342153120626, 11145.442727863736, 11413.486393112164, 11561.768764624829, 11747.823782643438, 11962.10284228669, 40234.79882600568, 40513.18973620452, 41330.173396531296, 42527.787361063, 43250.78374608107, 52240.47759522356, 52344.47655523548, 54778.0, 59404.1089383651, 60591.69704151627, 8771.736847894574, 8828.646854125764, 9007.369484136048, 9107.180353768194, 9241.537923103953, 12412.689682757642, 12581.87418125807, 12602.621921342374, 12988.935055324662, 13098.541551045668, 11100.833487497855, 11785.823212651645, 11829.822552661544, 11992.340421276565, 12237.632871013884, 11472.820472065658, 11562.653120406401, 11574.537018519164, 11865.0, 11881.168318217642, 11508.827367589325, 11734.882651173379, 11857.11071669611, 12011.099167562294, 12223.388830558615, 8295.95852020736, 8554.743357699279, 9113.270938325051, 9299.907000929905, 9356.06439356059, 8619.051904290649, 11104.944475277573, 11646.126540509325, 11650.825237621273, 11667.299962002307], "value": 8908.24313685664, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1602.0307713833133, 1624.6344572471196, 1818.7544681467998, 1867.0534348101307, 1891.659501289768, 8330.541820199887, 9322.347435679505, 9398.24814014879, 10029.949850250758, 10087.899121008788, 9542.904570954288, 9788.85316720248, 9897.4556399398, 9918.0, 9921.702348929526, 2402.351365131418, 2431.9513609727883, 2445.510897820443, 2445.6331550267423, 2457.3979375053136, 2395.461021270209, 2406.7232268289195, 2439.9268021959283, 2448.4980578981267, 2453.521563295158, 2435.038159926828, 2442.4016116051594, 2458.5697502937005, 2475.8762061896923, 2476.987615061931, 2338.988305058472, 2339.099446713011, 2349.283468542094, 2360.586897292967, 2387.53443078598, 2352.270796053229, 2386.5942789725614, 2393.6050551658955, 2393.700787401575, 2405.0620258099357, 9780.364276322087, 9881.654142104991, 9892.95053524735, 9893.406395616214, 9908.405495670282, 5561.694106824123, 5715.657060576337, 5724.570657200727, 5725.055365864628, 5729.942700573024, 3555.839987200582, 3589.8205089745406, 3652.141746689526, 3653.536717197137, 3683.8158092095287, 1110.74452875839, 1119.5913491575516, 1150.3385553306798, 1157.1668398752884, 1168.1472525056672, 13705.520306789314, 13725.245111518863, 13726.245056521888, 13739.793903091462, 13769.586912392544, 4764.617691154423, 4790.353302304214, 4801.615870730333, 4840.515948405202, 4911.18965370717, 11555.768884622428, 11575.826362604568, 11583.305001699924, 11587.304761714324, 11596.188266821242, 5500.80747173847, 5561.193626924073, 5585.329760428694, 5597.104463285855, 5601.495865372136, 4366.868993930188, 4953.083679519301, 5052.873678158076, 5078.415982162025, 5103.897922041539, 4320.65434765218, 4324.113556720865, 4420.84527041552, 4436.401085853433, 4457.751829487748, 1294.4045738959996, 1313.8817506424468, 1317.7496275707585, 1326.1247576599399, 1330.5077121464926, 912.6121398405651, 977.6969139566758, 1048.5019615682643, 1049.8005378978123, 1052.6578861869923, 6525.641089740015, 6534.607923524603, 6557.409833115043, 6559.967200164064, 6678.165229346332, 441.85860524631806, 448.18206772640065, 450.4189595421935, 1026.6355443817338, 1106.1095817866694, 4415.249407600716, 4423.314386270134, 4442.777861106932, 4461.04087621163, 4481.148581769454, 12088.879111208951, 12525.624231273076, 12565.811512827315, 12587.685307867376, 12591.433385497674, 11096.223264371416, 11557.075433965263, 11577.826332605018, 11842.526298947945, 12486.313252771002, 9095.863562046574, 9221.400608960395, 9261.814763704688, 9266.675666351646, 9267.675631353006, 6614.437772789367, 6795.218549866631, 6874.8625027500175, 6885.345892140239, 6933.479989000744, 11152.386618736047, 11241.100711942861, 11322.207445479067, 11478.598249061242, 11672.883271167519, 11523.0, 11594.01450876685, 11627.825582616098, 11642.5342986283, 11771.23486973344, 8237.752867413987, 8384.706535271236, 8544.145585441405, 8565.743027709179, 8603.569821508774, 9355.578998944928, 9433.386829856037, 9444.716658500256, 9537.046295370408, 9537.47543885093, 7851.332636725942, 7963.562004089599, 8057.717979870677, 8092.433529652868, 8131.105578386491, 7957.562334071682, 8135.064467586067, 8144.144864789112, 8162.591870406337, 8199.467034642968, 7233.927660723325, 7237.203907570269, 7273.636318184175, 7390.593517356597, 7460.0, 5529.419410961791, 5561.694106824001, 5605.467480589337, 5619.719014049363, 5806.622569532967, 5578.30271216098, 5616.466435688603, 5770.221020162225, 5790.855228619318, 5936.1095835625], "value": 4968.01507719482, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.1121249744456, 329.92576670249196, 329.9851506682199, 332.58925227344224, 333.1870236622642, 5807.838432313539, 5826.563007774413, 5868.589198756089, 5882.794102206423, 5883.88232235355, 5700.7719691212405, 5713.971430142843, 5743.942560574393, 5750.884982300351, 5759.769609215637, 4218.810153543091, 4337.371081193225, 4350.630196433307, 4366.956330436703, 4367.585079417466, 4334.2662934826, 4336.392904993303, 4341.240282950487, 4348.347747837818, 4362.302031674933, 4336.132773445308, 4338.826446942118, 4347.586979236968, 4362.367456718774, 4381.430414046185, 4092.0792821615046, 4276.711959101896, 4292.205941900758, 4296.820352938834, 4362.19299429605, 3706.425504046877, 3711.0722319420147, 3721.292954338694, 3735.906602334936, 3753.7612587845947, 5494.2857428534435, 5499.477549632778, 5502.58730595207, 5527.811520523067, 5544.556435485151, 4952.356193694796, 5009.44896061433, 5072.72100034498, 5083.288339632454, 5091.159958606825, 5993.730282137314, 5995.280566332024, 6029.6382217066675, 6040.154378387029, 6071.544634152457, 4212.873613791591, 4226.957730422687, 4258.616724494779, 4261.914761704748, 4263.87208383749, 6051.425114614105, 6253.656048917308, 6253.6873156342, 6277.305127615518, 6283.560150789446, 4259.7231179973205, 4262.595053469915, 4285.828566857291, 4290.764007979528, 4302.182585308781, 4340.262155433584, 4349.804258808361, 4352.194843953878, 4362.8909277267685, 4363.432753741993, 4781.880452988703, 4970.850874473772, 4981.277714731344, 5008.499150084962, 5059.215821547666, 5993.101034844721, 6003.669798161056, 6065.575409721366, 6082.391760823883, 6084.178635884187, 1483.3325003748357, 1490.225082956878, 1497.8502149785152, 1513.6518600721895, 1521.3382178752279, 943.1700103908654, 963.8795150606175, 986.2898712926682, 994.1996692662332, 995.0845222395413, 5872.765089396376, 5947.107933809876, 5949.583529153005, 5963.880722385528, 5970.462658360769, 6186.752529898844, 6226.377362263737, 6238.189035425366, 6245.469135123565, 6258.3428739982555, 5723.399043100415, 5884.734377264784, 5901.940980590225, 5930.703464826742, 5934.732937017759, 3304.487804390323, 3328.9334213315597, 3330.7002369786837, 3352.228987332927, 3366.9326613467592, 6248.969085047378, 6344.460720838688, 6429.3570642936265, 6432.485401167895, 6458.6770661466735, 6318.273398559132, 6327.0, 6331.05034244867, 6346.904796428057, 6361.363863613602, 6209.565330426917, 6346.238451385863, 6423.42189202974, 6455.160829092188, 6473.643949582818, 6175.96912015437, 6211.223597050369, 6215.160953271251, 6252.9374706254175, 6272.247330320391, 6134.14122022926, 6147.44672979434, 6159.907601385893, 6164.106204600218, 6180.351063138306, 6182.0, 6571.4557079087335, 6585.374389432996, 6604.966975165094, 6608.768693095719, 4230.8519201827785, 4309.5044069932355, 4323.891902702332, 4325.594181890961, 4326.502452218036, 6262.46769024638, 6277.497800176066, 6323.20959880015, 6357.8383349661335, 6457.289698133108, 6081.483073938764, 6092.482139018233, 6102.847428814315, 6125.84685382869, 6161.476274516716, 5900.970495147497, 6109.694515274307, 6116.755329786759, 6124.693765311627, 6134.2332208473945, 5882.647041177543, 5975.25309336333, 5983.34183239852, 5999.040153575407, 6019.0, 6162.661053642093, 6167.506599471942, 6187.628742275478, 6193.318734939063, 6204.534659900435, 5980.461758441761, 6064.241969753781, 6095.695215239309, 6102.115193297036, 6104.511639068953], "value": 4566.254233407907, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [724.0008787872735, 792.5561685456145, 891.7770557360661, 891.933105017123, 929.4098247612768, 13641.590752277436, 13694.383752731126, 13704.10923289988, 13710.657233569165, 14464.059836110659, 15134.772978405334, 15361.615959601015, 15444.0, 15511.146886921215, 15579.532614021597, 817.3175398542195, 860.5826174305441, 865.4980111535294, 867.7353407210799, 868.1145231863495, 853.0445900591346, 853.4623187391966, 854.7393045121236, 856.1353033436212, 858.1504310732362, 896.238197532099, 896.9551522423885, 897.685809966513, 898.5956319656149, 902.4495058014605, 886.9822603547925, 887.5163036145252, 889.799795046116, 890.528020149322, 892.2148509311829, 652.6540933305356, 658.9571677840925, 659.9967000164968, 660.3297652882328, 663.9004149377583, 15391.923040384838, 15561.221938903009, 15572.37710491579, 15583.45457908979, 15586.766198507032, 14444.422223111169, 14650.853491465055, 14797.704045919021, 14805.259737013213, 14842.703145937021, 11707.531698732126, 11789.587364442203, 11831.230969986924, 11834.112441566916, 12013.159078864483, 3977.880663580097, 4006.0, 4028.234635419261, 4032.5160980682213, 4035.1324465240114, 88474.0, 89680.10319896783, 90197.54901225517, 90225.54887225588, 90554.09445905522, 10740.30188037775, 10762.3542587445, 10802.886335626019, 10897.237193396386, 11200.271982321123, 21082.578348432944, 21129.26047588327, 21135.8943205283, 21203.0, 21226.150953961667, 8064.395170362246, 8113.066997295268, 8325.542095184703, 8521.318294536422, 8588.055313915464, 5072.644914855925, 5159.380874295109, 5243.292155559027, 5264.394594621667, 5313.601479889024, 3551.271989242199, 3661.9450708239397, 3662.61542538035, 3663.8900832975055, 3671.394219953731, 550.4632982841697, 604.039577072457, 613.2885852411182, 613.7913109542777, 614.4807637546267, 734.6473692627569, 737.6053811210976, 740.526063319476, 758.5398108640844, 780.7306479264645, 10277.434741089312, 10439.37363758177, 10517.9474102629, 10571.260011799253, 10642.201834862417, 752.2176935986622, 836.4354061008795, 841.066416277931, 847.0766864118141, 850.974470765878, 4927.778249978688, 4983.103041452573, 4991.875203119951, 5002.124628190125, 5015.84952451427, 126112.0, 128879.0, 131781.0, 134725.0, 135718.0, 108274.45862770636, 109456.0, 110392.0, 110707.0, 114359.0, 66319.66840165865, 101029.0, 102147.48926255321, 102406.0, 103752.48123759333, 9191.724248272563, 9235.630574776933, 9509.76225594338, 9512.923159859814, 9701.126898579161, 56742.14886776619, 57569.272921812415, 58867.70566147142, 59677.70161149166, 60981.39018609757, 78912.0, 88172.5591372039, 88480.0, 88746.55626721826, 90971.0, 11706.590269340531, 11717.238379505305, 12059.57791477294, 12059.81910271329, 12170.330631815335, 12914.870851291367, 13171.736565268833, 13273.0, 13366.064375493623, 13383.799243011557, 12374.13381063317, 12540.937295313464, 12553.184043037174, 12666.683332916751, 13008.479660813462, 12086.697832554257, 12111.152219344562, 12114.15200935926, 12717.682057948256, 12874.227546347249, 12306.200096993667, 12520.749585008067, 12624.242545447303, 12674.302913339854, 13188.868111318763, 10608.0, 10689.839652405375, 10799.514021869192, 10893.836592451278, 10961.780764384826, 10650.254482186483, 10869.782604348027, 10881.564737410732, 10916.836247456453, 11054.723631908944], "value": 7778.913258936581, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1518.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 105.0, 112.0, 110.0, 111.0, 111.0, 112.0, 114.0, 111.0, 110.0], "value": 110.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 143.0, 152.0, 176.0, 131.0, 152.0, 153.0, 136.0, 182.0, 130.0], "value": 150.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 161.0, 162.0, 163.0, 162.0, 161.0, 163.0, 163.0, 162.0], "value": 162.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 74.0, 75.0, 168.0, 74.0, 75.0, 83.0, 77.0, 168.0, 77.0], "value": 94.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 83.0, 142.0, 84.0, 84.0, 83.0, 142.0, 84.0, 83.0, 153.0], "value": 102.7, "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, 117.0, 118.0], "value": 117.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 146.0, 146.0, 146.0, 145.0, 146.0, 148.0, 146.0, 146.0, 146.0], "value": 146.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 95.0, 96.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 95.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [52.0, 55.0, 52.0, 53.0, 62.0, 53.0, 52.0, 53.0, 54.0, 52.0], "value": 53.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 59.0, 60.0, 60.0, 61.0, 60.0, 60.0, 60.0, 60.0, 61.0], "value": 60.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 76.0, 81.0, 77.0, 78.0, 86.0, 78.0, 77.0, 102.0, 77.0], "value": 81.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 123.0, 138.0, 123.0, 131.0, 119.0, 118.0, 127.0, 142.0, 122.0], "value": 126.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 155.0, 162.0, 153.0, 153.0, 160.0, 153.0, 154.0, 154.0, 156.0], "value": 155.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 63.0, 64.0, 63.0, 66.0, 62.0, 60.0, 59.0, 61.0, 69.0], "value": 62.9, "unit": "score"}]}]} 16:52:04 INFO - Return code: 0 16:52:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:52:04 ERROR - # TBPL SUCCESS # 16:52:04 INFO - Running post-action listener: _resource_record_post_action 16:52:04 INFO - ##### 16:52:04 INFO - ##### Finished run-tests step (success) 16:52:04 INFO - ##### 16:52:04 INFO - Running post-run listener: _resource_record_post_run 16:52:04 INFO - Total resource usage - Wall time: 1184s; CPU: 13.0%; Read bytes: 28672; Write bytes: 464633856; Read time: 28; Write time: 407524 16:52:04 INFO - TinderboxPrint: CPU usage
13.3% 16:52:04 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 28 16:52:04 INFO - TinderboxPrint: I/O write bytes / time
464,633,856 / 407,524 16:52:04 INFO - TinderboxPrint: CPU idle
8,192.2 (86.7%) 16:52:04 INFO - TinderboxPrint: CPU user
1,205.4 (12.8%) 16:52:04 INFO - TinderboxPrint: Swap in / out
0 / 0 16:52:04 INFO - install - Wall time: 13s; CPU: 15.0%; Read bytes: 0; Write bytes: 242184192; Read time: 0; Write time: 279292 16:52:04 INFO - run-tests - Wall time: 1171s; CPU: 13.0%; Read bytes: 4096; Write bytes: 222138368; Read time: 16; Write time: 128208 16:52:04 INFO - Running post-run listener: _upload_blobber_files 16:52:04 INFO - Blob upload gear active. 16:52:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:52:04 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 16:52:04 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:52:04 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:52:05 INFO - (blobuploader) - INFO - Open directory for files ... 16:52:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:52:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:52:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:06 INFO - (blobuploader) - INFO - Done attempting. 16:52:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:52:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:52:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:06 INFO - (blobuploader) - INFO - Done attempting. 16:52:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:52:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:52:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:52:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:52:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:52:10 INFO - (blobuploader) - INFO - Done attempting. 16:52:10 INFO - (blobuploader) - INFO - Iteration through files over. 16:52:10 INFO - Return code: 0 16:52:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:52:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:52:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5afeeec475681df85b95337ca96bad890f195c874349a060aec5ba172de60e506c5d93a81932477ddd2af3e30e6935a6585d9d40101db700b757d5957cf826ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e06b42e16a8c10c8eab0a49544424654ffc0dd23f4a76c2554cdc9bc4997e563a8865d663da7f0103df1aa3dbfb2842944bba5e886b72f5703ea5b770edc23f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a2c67f356f6f6641f4a8a5e8b18de256d43c747e393443f1c07d8e0b459cdfaad9b1e32ca8afeb1760eba7bf1f3f8a663fe4e64cb5e1766f5c375d309b7f4b5"} 16:52:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:52:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:52:10 INFO - Contents: 16:52:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5afeeec475681df85b95337ca96bad890f195c874349a060aec5ba172de60e506c5d93a81932477ddd2af3e30e6935a6585d9d40101db700b757d5957cf826ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e06b42e16a8c10c8eab0a49544424654ffc0dd23f4a76c2554cdc9bc4997e563a8865d663da7f0103df1aa3dbfb2842944bba5e886b72f5703ea5b770edc23f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a2c67f356f6f6641f4a8a5e8b18de256d43c747e393443f1c07d8e0b459cdfaad9b1e32ca8afeb1760eba7bf1f3f8a663fe4e64cb5e1766f5c375d309b7f4b5"} 16:52:10 INFO - Running post-run listener: copy_logs_to_upload_dir 16:52:10 INFO - Copying logs to upload dir... 16:52:10 INFO - mkdir: /builds/slave/test/build/upload/logs 16:52:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1224.414682 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 24 secs) (at 2016-08-01 16:52:10.490324) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 16:52:10.493751) ========= 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=62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5afeeec475681df85b95337ca96bad890f195c874349a060aec5ba172de60e506c5d93a81932477ddd2af3e30e6935a6585d9d40101db700b757d5957cf826ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e06b42e16a8c10c8eab0a49544424654ffc0dd23f4a76c2554cdc9bc4997e563a8865d663da7f0103df1aa3dbfb2842944bba5e886b72f5703ea5b770edc23f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a2c67f356f6f6641f4a8a5e8b18de256d43c747e393443f1c07d8e0b459cdfaad9b1e32ca8afeb1760eba7bf1f3f8a663fe4e64cb5e1766f5c375d309b7f4b5"} build_url:https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011320 build_url: 'https://queue.taskcluster.net/v1/task/KOBHRb6GQaWc6WL_UiFKbg/artifacts/public/build/firefox-49.0.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5afeeec475681df85b95337ca96bad890f195c874349a060aec5ba172de60e506c5d93a81932477ddd2af3e30e6935a6585d9d40101db700b757d5957cf826ac", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5e06b42e16a8c10c8eab0a49544424654ffc0dd23f4a76c2554cdc9bc4997e563a8865d663da7f0103df1aa3dbfb2842944bba5e886b72f5703ea5b770edc23f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9a2c67f356f6f6641f4a8a5e8b18de256d43c747e393443f1c07d8e0b459cdfaad9b1e32ca8afeb1760eba7bf1f3f8a663fe4e64cb5e1766f5c375d309b7f4b5"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-01 16:52:10.518395) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:52:10.518689) ========= 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=62c568580acbfdaac3eec7be000001dc-1470094304.393202-787753966 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004488 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-01 16:52:10.604068) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 16:52:10.604386) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-01 16:52:10.604659) ========= ========= Total master_lag: 0.17 =========