builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-011 starttime: 1461647427.84 results: success (0) buildid: 20160425193441 builduid: 349e45bbfbf844b1ab9e439c227fc938 revision: a96d8ef42b26cb49d156f981e2aa745eadb1f17e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.837137) ========= master: http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.837759) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.838056) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.868790) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.869058) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-25 22:10:27-- 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% 46.6M=0s 2016-04-25 22:10:27 (46.6 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.089196 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.971506) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:27.971822) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017362 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:28.021099) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 22:10:28.021405) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev a96d8ef42b26cb49d156f981e2aa745eadb1f17e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev a96d8ef42b26cb49d156f981e2aa745eadb1f17e --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=5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-25 22:10:28,102 truncating revision to first 12 chars 2016-04-25 22:10:28,102 Setting DEBUG logging. 2016-04-25 22:10:28,102 attempt 1/10 2016-04-25 22:10:28,102 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/a96d8ef42b26?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-25 22:10:29,351 unpacking tar archive at: mozilla-inbound-a96d8ef42b26/testing/mozharness/ program finished with exit code 0 elapsedTime=1.545674 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-04-25 22:10:29.579744) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:29.580053) ========= script_repo_revision: a96d8ef42b26cb49d156f981e2aa745eadb1f17e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:29.580473) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:29.580761) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-25 22:10:29.590879) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-25 22:10:29.591166) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Inbound', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Inbound'] environment: DISPLAY=:0 HOME=/home/cltbld LANG=en_US.UTF-8 LANGUAGE=en_US:en LOGNAME=cltbld MAIL=/var/mail/cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NODE_PATH=/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript NO_EM_RESTART=1 PATH=/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test SHELL=/bin/bash SHLVL=1 TERM=linux TMOUT=86400 USER=cltbld XDG_SESSION_COOKIE=5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:10:29 INFO - MultiFileLogger online at 20160425 22:10:29 in /builds/slave/test 22:10:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 22:10:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:10:29 INFO - {'append_to_log': False, 22:10:29 INFO - 'base_work_dir': '/builds/slave/test', 22:10:29 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 22:10:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:10:29 INFO - 'branch': 'Mozilla-Inbound', 22:10:29 INFO - 'buildbot_json_path': 'buildprops.json', 22:10:29 INFO - 'config_files': ('talos/linux_config.py',), 22:10:29 INFO - 'default_actions': ('clobber', 22:10:29 INFO - 'read-buildbot-config', 22:10:29 INFO - 'download-and-extract', 22:10:29 INFO - 'populate-webroot', 22:10:29 INFO - 'create-virtualenv', 22:10:29 INFO - 'install', 22:10:29 INFO - 'run-tests'), 22:10:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:10:29 INFO - 'download_minidump_stackwalk': True, 22:10:29 INFO - 'download_symbols': 'ondemand', 22:10:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:10:29 INFO - 'tooltool.py': '/tools/tooltool.py', 22:10:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:10:29 INFO - '/tools/misc-python/virtualenv.py')}, 22:10:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:10:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:10:29 INFO - 'installer_path': 'installer.exe', 22:10:29 INFO - 'log_level': 'info', 22:10:29 INFO - 'log_name': 'talos', 22:10:29 INFO - 'log_to_console': True, 22:10:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:10:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:10:29 INFO - 'opt_config_files': (), 22:10:29 INFO - 'pip_index': False, 22:10:29 INFO - 'sps_profile': False, 22:10:29 INFO - 'sps_profile_interval': 0, 22:10:29 INFO - 'suite': 'dromaeojs', 22:10:29 INFO - 'system_bits': '32', 22:10:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:10:29 INFO - 'title': 'talos-linux64-ix-011', 22:10:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:10:29 INFO - 'use_talos_json': True, 22:10:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:10:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:10:29 INFO - 'work_dir': 'build'} 22:10:29 INFO - ##### 22:10:29 INFO - ##### Running clobber step. 22:10:29 INFO - ##### 22:10:29 INFO - Running pre-action listener: _resource_record_pre_action 22:10:29 INFO - Running main action method: clobber 22:10:29 INFO - rmtree: /builds/slave/test/build 22:10:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:10:30 INFO - Running post-action listener: _resource_record_post_action 22:10:30 INFO - ##### 22:10:30 INFO - ##### Running read-buildbot-config step. 22:10:30 INFO - ##### 22:10:30 INFO - Running pre-action listener: _resource_record_pre_action 22:10:30 INFO - Running main action method: read_buildbot_config 22:10:30 INFO - Using buildbot properties: 22:10:30 INFO - { 22:10:30 INFO - "project": "", 22:10:30 INFO - "product": "firefox", 22:10:30 INFO - "script_repo_revision": "production", 22:10:30 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 22:10:30 INFO - "repository": "", 22:10:30 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 22:10:30 INFO - "buildid": "20160425193441", 22:10:30 INFO - "pgo_build": "True", 22:10:30 INFO - "basedir": "/builds/slave/test", 22:10:30 INFO - "buildnumber": 1752, 22:10:30 INFO - "slavename": "talos-linux64-ix-011", 22:10:30 INFO - "master": "http://buildbot-master103.bb.releng.scl3.mozilla.com:8201/", 22:10:30 INFO - "platform": "ubuntu64_hw", 22:10:30 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 22:10:30 INFO - "branch": "mozilla-inbound", 22:10:30 INFO - "repo_path": "integration/mozilla-inbound", 22:10:30 INFO - "revision": "a96d8ef42b26cb49d156f981e2aa745eadb1f17e", 22:10:30 INFO - "stage_platform": "linux64-pgo", 22:10:30 INFO - "builduid": "349e45bbfbf844b1ab9e439c227fc938", 22:10:30 INFO - "slavebuilddir": "test" 22:10:30 INFO - } 22:10:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 22:10:30 INFO - Running post-action listener: _resource_record_post_action 22:10:30 INFO - ##### 22:10:30 INFO - ##### Running download-and-extract step. 22:10:30 INFO - ##### 22:10:30 INFO - Running pre-action listener: _resource_record_pre_action 22:10:30 INFO - Running main action method: download_and_extract 22:10:30 INFO - mkdir: /builds/slave/test/build/tests 22:10:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:30 INFO - https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:10:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:10:33 INFO - Downloaded 1361 bytes. 22:10:33 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 22:10:33 INFO - Using the following test package requirements: 22:10:33 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:33 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:10:33 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:10:33 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'jsshell-linux-x86_64.zip'], 22:10:33 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:10:33 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:33 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:10:33 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:10:33 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:10:33 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:33 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:10:33 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:10:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:33 INFO - https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:10:33 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:33 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:33 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:33 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:10:41 INFO - Downloaded 23863165 bytes. 22:10:41 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:41 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:10:42 INFO - Return code: 0 22:10:42 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:10:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:42 INFO - https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:10:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 22:10:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:10:55 INFO - Downloaded 23863165 bytes. 22:10:55 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:55 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:10:57 INFO - Return code: 0 22:10: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')]} 22:10:57 INFO - https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:10:57 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:57 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:57 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:10:58 INFO - Downloaded 10840145 bytes. 22:10:58 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:58 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:10:58 INFO - Return code: 0 22:10:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:58 INFO - https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:10:58 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:58 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:58 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:10:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:11:02 INFO - Downloaded 58361591 bytes. 22:11:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:11:02 INFO - mkdir: /builds/slave/test/properties 22:11:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:11:02 INFO - Writing to file /builds/slave/test/properties/build_url 22:11:02 INFO - Contents: 22:11:02 INFO - build_url:https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 22:11:03 INFO - Running post-action listener: _resource_record_post_action 22:11:03 INFO - Running post-action listener: set_extra_try_arguments 22:11:03 INFO - ##### 22:11:03 INFO - ##### Running populate-webroot step. 22:11:03 INFO - ##### 22:11:03 INFO - Running pre-action listener: _resource_record_pre_action 22:11:03 INFO - Running main action method: populate_webroot 22:11:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:11:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:11:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:11:03 INFO - '--tppagecycles', 22:11:03 INFO - '7'], 22:11:03 INFO - 'tests': ['tsvgm']}}, 22:11:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:11:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:11:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:11:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:11:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:11:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:11:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['damp', 'tps']}, 22:11:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'tests': ['damp', 'tps']}, 22:11:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['dromaeo_dom']}, 22:11:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:11:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['a11yr', 22:11:03 INFO - 'ts_paint', 22:11:03 INFO - 'tpaint', 22:11:03 INFO - 'sessionrestore', 22:11:03 INFO - 'sessionrestore_no_auto_restore', 22:11:03 INFO - 'tabpaint']}, 22:11:03 INFO - 'other-e10s': {'tests': ['a11yr', 22:11:03 INFO - 'ts_paint', 22:11:03 INFO - 'tpaint', 22:11:03 INFO - 'sessionrestore', 22:11:03 INFO - 'sessionrestore_no_auto_restore', 22:11:03 INFO - 'tabpaint']}, 22:11:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:11:03 INFO - 'ts_paint', 22:11:03 INFO - 'tpaint', 22:11:03 INFO - 'sessionrestore', 22:11:03 INFO - 'sessionrestore_no_auto_restore', 22:11:03 INFO - 'tabpaint']}, 22:11:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:11:03 INFO - 'ts_paint', 22:11:03 INFO - 'tpaint', 22:11:03 INFO - 'sessionrestore', 22:11:03 INFO - 'sessionrestore_no_auto_restore', 22:11:03 INFO - 'tabpaint']}, 22:11:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['a11yr', 22:11:03 INFO - 'ts_paint', 22:11:03 INFO - 'tpaint', 22:11:03 INFO - 'sessionrestore', 22:11:03 INFO - 'sessionrestore_no_auto_restore', 22:11:03 INFO - 'tabpaint']}, 22:11:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['a11yr', 22:11:03 INFO - 'ts_paint', 22:11:03 INFO - 'tpaint', 22:11:03 INFO - 'sessionrestore', 22:11:03 INFO - 'sessionrestore_no_auto_restore', 22:11:03 INFO - 'tabpaint']}, 22:11:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['tsvgx', 22:11:03 INFO - 'tsvgr_opacity', 22:11:03 INFO - 'tart', 22:11:03 INFO - 'tscrollx', 22:11:03 INFO - 'cart']}, 22:11:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:11:03 INFO - 'tsvgr_opacity', 22:11:03 INFO - 'tart', 22:11:03 INFO - 'tscrollx', 22:11:03 INFO - 'cart']}, 22:11:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'talos_options': ['--disable-e10s'], 22:11:03 INFO - 'tests': ['tp5o']}, 22:11:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'tests': ['tp5o']}, 22:11:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'talos_options': ['--disable-e10s', 22:11:03 INFO - '--xperf_path', 22:11:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:11:03 INFO - 'tests': ['tp5n']}, 22:11:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:11:03 INFO - 'talos_options': ['--xperf_path', 22:11:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:11:03 INFO - 'tests': ['tp5n']}}, 22:11:03 INFO - 'talos.zip': {'path': '', 22:11:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:11:03 INFO - Running post-action listener: _resource_record_post_action 22:11:03 INFO - ##### 22:11:03 INFO - ##### Running create-virtualenv step. 22:11:03 INFO - ##### 22:11:03 INFO - Running pre-action listener: _resource_record_pre_action 22:11:03 INFO - Running main action method: create_virtualenv 22:11:03 INFO - Creating virtualenv /builds/slave/test/build/venv 22:11:03 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:11:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:11:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:11:03 INFO - Using real prefix '/usr' 22:11:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:11:03 INFO - Installing distribute.............................................................................................................................................................................................done. 22:11:05 INFO - Installing pip.................done. 22:11:05 INFO - Return code: 0 22:11:05 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11: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', 'pip>=1.5'] in /builds/slave/test/build 22:11: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 pip>=1.5 22:11:05 INFO - Using env: {'DISPLAY': ':0', 22:11:05 INFO - 'HOME': '/home/cltbld', 22:11:05 INFO - 'LANG': 'en_US.UTF-8', 22:11:05 INFO - 'LANGUAGE': 'en_US:en', 22:11:05 INFO - 'LOGNAME': 'cltbld', 22:11:05 INFO - 'MAIL': '/var/mail/cltbld', 22:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:05 INFO - 'MOZ_NO_REMOTE': '1', 22:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:05 INFO - 'NO_EM_RESTART': '1', 22:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:05 INFO - 'PWD': '/builds/slave/test', 22:11:05 INFO - 'SHELL': '/bin/bash', 22:11:05 INFO - 'SHLVL': '1', 22:11:05 INFO - 'TERM': 'linux', 22:11:05 INFO - 'TMOUT': '86400', 22:11:05 INFO - 'USER': 'cltbld', 22:11:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:05 INFO - '_': '/tools/buildbot/bin/python'} 22:11:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:05 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:11:05 INFO - Cleaning up... 22:11:05 INFO - Return code: 0 22:11:05 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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', 'psutil>=0.7.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11: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', 'psutil>=0.7.1'] in /builds/slave/test/build 22:11: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 psutil>=0.7.1 22:11:05 INFO - Using env: {'DISPLAY': ':0', 22:11:05 INFO - 'HOME': '/home/cltbld', 22:11:05 INFO - 'LANG': 'en_US.UTF-8', 22:11:05 INFO - 'LANGUAGE': 'en_US:en', 22:11:05 INFO - 'LOGNAME': 'cltbld', 22:11:05 INFO - 'MAIL': '/var/mail/cltbld', 22:11:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:05 INFO - 'MOZ_NO_REMOTE': '1', 22:11:05 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:05 INFO - 'NO_EM_RESTART': '1', 22:11:05 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:05 INFO - 'PWD': '/builds/slave/test', 22:11:05 INFO - 'SHELL': '/bin/bash', 22:11:05 INFO - 'SHLVL': '1', 22:11:05 INFO - 'TERM': 'linux', 22:11:05 INFO - 'TMOUT': '86400', 22:11:05 INFO - 'USER': 'cltbld', 22:11:05 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:05 INFO - '_': '/tools/buildbot/bin/python'} 22:11:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:06 INFO - Downloading/unpacking psutil>=0.7.1 22:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:11:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:11:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:11:08 INFO - Installing collected packages: psutil 22:11:08 INFO - Running setup.py install for psutil 22:11:08 INFO - building 'psutil._psutil_linux' extension 22:11:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:11:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:11:08 INFO - building 'psutil._psutil_posix' extension 22:11:08 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:11:08 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:11:08 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:11:08 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:11:08 INFO - Successfully installed psutil 22:11:08 INFO - Cleaning up... 22:11:08 INFO - Return code: 0 22:11:08 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:11: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')]} 22:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11: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')]} 22:11:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:08 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.0.0']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:08 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.0.0'] in /builds/slave/test/build 22:11:08 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.0.0 22:11:08 INFO - Using env: {'DISPLAY': ':0', 22:11:08 INFO - 'HOME': '/home/cltbld', 22:11:08 INFO - 'LANG': 'en_US.UTF-8', 22:11:08 INFO - 'LANGUAGE': 'en_US:en', 22:11:08 INFO - 'LOGNAME': 'cltbld', 22:11:08 INFO - 'MAIL': '/var/mail/cltbld', 22:11:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:08 INFO - 'MOZ_NO_REMOTE': '1', 22:11:08 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:08 INFO - 'NO_EM_RESTART': '1', 22:11:08 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:08 INFO - 'PWD': '/builds/slave/test', 22:11:08 INFO - 'SHELL': '/bin/bash', 22:11:08 INFO - 'SHLVL': '1', 22:11:08 INFO - 'TERM': 'linux', 22:11:08 INFO - 'TMOUT': '86400', 22:11:08 INFO - 'USER': 'cltbld', 22:11:08 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:08 INFO - '_': '/tools/buildbot/bin/python'} 22:11:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:08 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:10 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:11:10 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:11:10 INFO - Installing collected packages: mozsystemmonitor 22:11:10 INFO - Running setup.py install for mozsystemmonitor 22:11:10 INFO - Successfully installed mozsystemmonitor 22:11:10 INFO - Cleaning up... 22:11:11 INFO - Return code: 0 22:11:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:11 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 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:11:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:11:11 INFO - Using env: {'DISPLAY': ':0', 22:11:11 INFO - 'HOME': '/home/cltbld', 22:11:11 INFO - 'LANG': 'en_US.UTF-8', 22:11:11 INFO - 'LANGUAGE': 'en_US:en', 22:11:11 INFO - 'LOGNAME': 'cltbld', 22:11:11 INFO - 'MAIL': '/var/mail/cltbld', 22:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:11 INFO - 'MOZ_NO_REMOTE': '1', 22:11:11 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:11 INFO - 'NO_EM_RESTART': '1', 22:11:11 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:11 INFO - 'PWD': '/builds/slave/test', 22:11:11 INFO - 'SHELL': '/bin/bash', 22:11:11 INFO - 'SHLVL': '1', 22:11:11 INFO - 'TERM': 'linux', 22:11:11 INFO - 'TMOUT': '86400', 22:11:11 INFO - 'USER': 'cltbld', 22:11:11 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:11 INFO - '_': '/tools/buildbot/bin/python'} 22:11:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:11 INFO - Downloading/unpacking blobuploader==1.2.4 22:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:13 INFO - Downloading blobuploader-1.2.4.tar.gz 22:11:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:11:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:11: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 22:11: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 22:11: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 22:11: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 22:11: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 22:11: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 22:11:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:11:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:11: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 22:11: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 22:11: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 22:11: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 22:11: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 22:11: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 22:11:13 INFO - Downloading docopt-0.6.1.tar.gz 22:11:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:11:13 INFO - Installing collected packages: blobuploader, requests, docopt 22:11:13 INFO - Running setup.py install for blobuploader 22:11:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:11:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:11:13 INFO - Running setup.py install for requests 22:11:14 INFO - Running setup.py install for docopt 22:11:14 INFO - Successfully installed blobuploader requests docopt 22:11:14 INFO - Cleaning up... 22:11:14 INFO - Return code: 0 22:11:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:14 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 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:14 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:11:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:11:14 INFO - Using env: {'DISPLAY': ':0', 22:11:14 INFO - 'HOME': '/home/cltbld', 22:11:14 INFO - 'LANG': 'en_US.UTF-8', 22:11:14 INFO - 'LANGUAGE': 'en_US:en', 22:11:14 INFO - 'LOGNAME': 'cltbld', 22:11:14 INFO - 'MAIL': '/var/mail/cltbld', 22:11:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:14 INFO - 'MOZ_NO_REMOTE': '1', 22:11:14 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:14 INFO - 'NO_EM_RESTART': '1', 22:11:14 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:14 INFO - 'PWD': '/builds/slave/test', 22:11:14 INFO - 'SHELL': '/bin/bash', 22:11:14 INFO - 'SHLVL': '1', 22:11:14 INFO - 'TERM': 'linux', 22:11:14 INFO - 'TMOUT': '86400', 22:11:14 INFO - 'USER': 'cltbld', 22:11:14 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:14 INFO - '_': '/tools/buildbot/bin/python'} 22:11:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:14 INFO - Running setup.py (path:/tmp/pip-5721xQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:14 INFO - Running setup.py (path:/tmp/pip-SgY0xE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:14 INFO - Running setup.py (path:/tmp/pip-O7axMM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:14 INFO - Running setup.py (path:/tmp/pip-0QTSIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:14 INFO - Running setup.py (path:/tmp/pip-n1fyzi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:15 INFO - Running setup.py (path:/tmp/pip-tK4NSS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:15 INFO - Running setup.py (path:/tmp/pip-qUQDCt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:15 INFO - Running setup.py (path:/tmp/pip-hytvwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:15 INFO - Running setup.py (path:/tmp/pip-vpS9Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:15 INFO - Running setup.py (path:/tmp/pip-xqjsLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:15 INFO - Running setup.py (path:/tmp/pip-Cqm4Jv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:15 INFO - Running setup.py (path:/tmp/pip-TmgI_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:15 INFO - Running setup.py (path:/tmp/pip-f8AbE6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:15 INFO - Running setup.py (path:/tmp/pip-4C6R1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:15 INFO - Running setup.py (path:/tmp/pip-h6pv60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:15 INFO - Running setup.py (path:/tmp/pip-9fhCEh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:15 INFO - Running setup.py (path:/tmp/pip-aEdpLZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:11:15 INFO - Running setup.py install for manifestparser 22:11:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:11:15 INFO - Running setup.py install for mozcrash 22:11:16 INFO - Running setup.py install for mozdebug 22:11:16 INFO - Running setup.py install for mozdevice 22:11:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Running setup.py install for mozfile 22:11:16 INFO - Running setup.py install for mozhttpd 22:11:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Running setup.py install for mozinfo 22:11:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Running setup.py install for mozInstall 22:11:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Running setup.py install for mozleak 22:11:16 INFO - Running setup.py install for mozlog 22:11:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:11:16 INFO - Running setup.py install for moznetwork 22:11:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:11:17 INFO - Running setup.py install for mozprocess 22:11:17 INFO - Running setup.py install for mozprofile 22:11:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:11:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:11:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:11:17 INFO - Running setup.py install for mozrunner 22:11:17 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:11:17 INFO - Running setup.py install for mozscreenshot 22:11:17 INFO - Running setup.py install for moztest 22:11:17 INFO - Running setup.py install for mozversion 22:11:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:11:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:11:17 INFO - Cleaning up... 22:11:17 INFO - Return code: 0 22:11:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:17 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 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:11:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:11:17 INFO - Using env: {'DISPLAY': ':0', 22:11:17 INFO - 'HOME': '/home/cltbld', 22:11:17 INFO - 'LANG': 'en_US.UTF-8', 22:11:17 INFO - 'LANGUAGE': 'en_US:en', 22:11:17 INFO - 'LOGNAME': 'cltbld', 22:11:17 INFO - 'MAIL': '/var/mail/cltbld', 22:11:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:17 INFO - 'MOZ_NO_REMOTE': '1', 22:11:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:17 INFO - 'NO_EM_RESTART': '1', 22:11:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:17 INFO - 'PWD': '/builds/slave/test', 22:11:17 INFO - 'SHELL': '/bin/bash', 22:11:17 INFO - 'SHLVL': '1', 22:11:17 INFO - 'TERM': 'linux', 22:11:17 INFO - 'TMOUT': '86400', 22:11:17 INFO - 'USER': 'cltbld', 22:11:17 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:17 INFO - '_': '/tools/buildbot/bin/python'} 22:11:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:11:17 INFO - Running setup.py (path:/tmp/pip-BDwNsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:11:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:11:17 INFO - Running setup.py (path:/tmp/pip-N6tdsB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:11:18 INFO - Running setup.py (path:/tmp/pip-H2mDqm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:11:18 INFO - Running setup.py (path:/tmp/pip-4oNgmQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:11:18 INFO - Running setup.py (path:/tmp/pip-f1G6qX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:11:18 INFO - Running setup.py (path:/tmp/pip-3AGL8X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:11:18 INFO - Running setup.py (path:/tmp/pip-1HoGMx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:11:18 INFO - Running setup.py (path:/tmp/pip-bexn_L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:11:18 INFO - Running setup.py (path:/tmp/pip-n0z72i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:11:18 INFO - Running setup.py (path:/tmp/pip-r0DGZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:11:18 INFO - Running setup.py (path:/tmp/pip-vjunOo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:11:18 INFO - Running setup.py (path:/tmp/pip-2Xp_iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:11:18 INFO - Running setup.py (path:/tmp/pip-pUqr0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:11:18 INFO - Running setup.py (path:/tmp/pip-CWs6hL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:18 INFO - Running setup.py (path:/tmp/pip-lMfqO3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:11:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:11:18 INFO - Running setup.py (path:/tmp/pip-y4WkSy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:11:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:11:19 INFO - Running setup.py (path:/tmp/pip-azd1mv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:11:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:11:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:20 INFO - Downloading blessings-1.6.tar.gz 22:11:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:11:21 INFO - Installing collected packages: blessings 22:11:21 INFO - Running setup.py install for blessings 22:11:21 INFO - Successfully installed blessings 22:11:21 INFO - Cleaning up... 22:11:21 INFO - Return code: 0 22:11:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:11:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:21 INFO - Reading from file tmpfile_stdout 22:11:21 INFO - Current package versions: 22:11:21 INFO - argparse == 1.2.1 22:11:21 INFO - blessings == 1.6 22:11:21 INFO - blobuploader == 1.2.4 22:11:21 INFO - docopt == 0.6.1 22:11:21 INFO - manifestparser == 1.1 22:11:21 INFO - mozInstall == 1.12 22:11:21 INFO - mozcrash == 0.17 22:11:21 INFO - mozdebug == 0.1 22:11:21 INFO - mozdevice == 0.48 22:11:21 INFO - mozfile == 1.2 22:11:21 INFO - mozhttpd == 0.7 22:11:21 INFO - mozinfo == 0.9 22:11:21 INFO - mozleak == 0.1 22:11:21 INFO - mozlog == 3.1 22:11:21 INFO - moznetwork == 0.27 22:11:21 INFO - mozprocess == 0.22 22:11:21 INFO - mozprofile == 0.28 22:11:21 INFO - mozrunner == 6.11 22:11:21 INFO - mozscreenshot == 0.1 22:11:21 INFO - mozsystemmonitor == 0.0 22:11:21 INFO - moztest == 0.7 22:11:21 INFO - mozversion == 1.4 22:11:21 INFO - psutil == 3.1.1 22:11:21 INFO - requests == 1.2.3 22:11:21 INFO - wsgiref == 0.1.2 22:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:21 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 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:11:21 INFO - Using env: {'DISPLAY': ':0', 22:11:21 INFO - 'HOME': '/home/cltbld', 22:11:21 INFO - 'LANG': 'en_US.UTF-8', 22:11:21 INFO - 'LANGUAGE': 'en_US:en', 22:11:21 INFO - 'LOGNAME': 'cltbld', 22:11:21 INFO - 'MAIL': '/var/mail/cltbld', 22:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:21 INFO - 'MOZ_NO_REMOTE': '1', 22:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:21 INFO - 'NO_EM_RESTART': '1', 22:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:21 INFO - 'PWD': '/builds/slave/test', 22:11:21 INFO - 'SHELL': '/bin/bash', 22:11:21 INFO - 'SHLVL': '1', 22:11:21 INFO - 'TERM': 'linux', 22:11:21 INFO - 'TMOUT': '86400', 22:11:21 INFO - 'USER': 'cltbld', 22:11:21 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:21 INFO - '_': '/tools/buildbot/bin/python'} 22:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:11:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:11:21 INFO - Cleaning up... 22:11:21 INFO - Return code: 0 22:11:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:21 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 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:11:21 INFO - Using env: {'DISPLAY': ':0', 22:11:21 INFO - 'HOME': '/home/cltbld', 22:11:21 INFO - 'LANG': 'en_US.UTF-8', 22:11:21 INFO - 'LANGUAGE': 'en_US:en', 22:11:21 INFO - 'LOGNAME': 'cltbld', 22:11:21 INFO - 'MAIL': '/var/mail/cltbld', 22:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:21 INFO - 'MOZ_NO_REMOTE': '1', 22:11:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:21 INFO - 'NO_EM_RESTART': '1', 22:11:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:21 INFO - 'PWD': '/builds/slave/test', 22:11:21 INFO - 'SHELL': '/bin/bash', 22:11:21 INFO - 'SHLVL': '1', 22:11:21 INFO - 'TERM': 'linux', 22:11:21 INFO - 'TMOUT': '86400', 22:11:21 INFO - 'USER': 'cltbld', 22:11:21 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:21 INFO - '_': '/tools/buildbot/bin/python'} 22:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:21 INFO - Downloading/unpacking jsonschema 22:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:23 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:11:23 INFO - Downloading/unpacking functools32 (from jsonschema) 22:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:23 INFO - Downloading functools32-3.2.3-2.tar.gz 22:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:11:23 INFO - warning: no files found matching '*.txt' 22:11:23 INFO - no previously-included directories found matching 'build' 22:11:23 INFO - no previously-included directories found matching 'dist' 22:11:23 INFO - no previously-included directories found matching '.git*' 22:11:23 INFO - Installing collected packages: jsonschema, functools32 22:11:24 INFO - Running setup.py install for functools32 22:11:24 INFO - warning: no files found matching '*.txt' 22:11:24 INFO - no previously-included directories found matching 'build' 22:11:24 INFO - no previously-included directories found matching 'dist' 22:11:24 INFO - no previously-included directories found matching '.git*' 22:11:24 INFO - Successfully installed jsonschema functools32 22:11:24 INFO - Cleaning up... 22:11:24 INFO - Return code: 0 22:11:24 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:11:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:11:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:11:24 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 0x7fc948d2ef10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1fa68a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x20715a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, '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': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:11:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:11:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:11:24 INFO - Using env: {'DISPLAY': ':0', 22:11:24 INFO - 'HOME': '/home/cltbld', 22:11:24 INFO - 'LANG': 'en_US.UTF-8', 22:11:24 INFO - 'LANGUAGE': 'en_US:en', 22:11:24 INFO - 'LOGNAME': 'cltbld', 22:11:24 INFO - 'MAIL': '/var/mail/cltbld', 22:11:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:24 INFO - 'MOZ_NO_REMOTE': '1', 22:11:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:24 INFO - 'NO_EM_RESTART': '1', 22:11:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:24 INFO - 'PWD': '/builds/slave/test', 22:11:24 INFO - 'SHELL': '/bin/bash', 22:11:24 INFO - 'SHLVL': '1', 22:11:24 INFO - 'TERM': 'linux', 22:11:24 INFO - 'TMOUT': '86400', 22:11:24 INFO - 'USER': 'cltbld', 22:11:24 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:24 INFO - '_': '/tools/buildbot/bin/python'} 22:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:11:24 INFO - Downloading/unpacking flake8 22:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:11:26 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:11:26 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:11:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:11:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:11:26 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:11:26 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:11:26 INFO - no previously-included directories found matching 'docs/_build' 22:11:26 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:11:27 INFO - Running setup.py install for pep8 22:11:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:11:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:11:27 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:11:27 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:11:27 INFO - no previously-included directories found matching 'docs/_build' 22:11:27 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:11:27 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:11:27 INFO - Cleaning up... 22:11:27 INFO - Return code: 0 22:11:27 INFO - Running post-action listener: _resource_record_post_action 22:11:27 INFO - Running post-action listener: _start_resource_monitoring 22:11:27 INFO - Starting resource monitoring. 22:11:27 INFO - ##### 22:11:27 INFO - ##### Running install step. 22:11:27 INFO - ##### 22:11:27 INFO - Running pre-action listener: _resource_record_pre_action 22:11:27 INFO - Running main action method: install 22:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:27 INFO - Reading from file tmpfile_stdout 22:11:27 INFO - Detecting whether we're running mozinstall >=1.0... 22:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:11:27 INFO - Reading from file tmpfile_stdout 22:11:27 INFO - Output received: 22:11:27 INFO - Usage: mozinstall [options] installer 22:11:27 INFO - Options: 22:11:27 INFO - -h, --help show this help message and exit 22:11:27 INFO - -d DEST, --destination=DEST 22:11:27 INFO - Directory to install application into. [default: 22:11:27 INFO - "/builds/slave/test"] 22:11:27 INFO - --app=APP Application being installed. [default: firefox] 22:11:27 INFO - mkdir: /builds/slave/test/build/application 22:11:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:11:44 INFO - Reading from file tmpfile_stdout 22:11:44 INFO - Output received: 22:11:44 INFO - /builds/slave/test/build/application/firefox/firefox 22:11:44 INFO - Running post-action listener: _resource_record_post_action 22:11:44 INFO - ##### 22:11:44 INFO - ##### Running run-tests step. 22:11:44 INFO - ##### 22:11:44 INFO - Running pre-action listener: _resource_record_pre_action 22:11:44 INFO - Running main action method: run_tests 22:11:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:11:44 INFO - Python 2.7.3 22:11:44 INFO - Return code: 0 22:11:44 INFO - grabbing minidump binary from tooltool 22:11:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:11:44 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2100410>, '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 0x2101310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x21017a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:11:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:11:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:11:44 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:11:44 INFO - Return code: 0 22:11:44 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:11:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:11:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:11:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:11:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:11:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:11:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:11:45 INFO - Return code: 0 22:11:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:11:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Inbound --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:11:45 INFO - Using env: {'DISPLAY': ':0', 22:11:45 INFO - 'HOME': '/home/cltbld', 22:11:45 INFO - 'LANG': 'en_US.UTF-8', 22:11:45 INFO - 'LANGUAGE': 'en_US:en', 22:11:45 INFO - 'LOGNAME': 'cltbld', 22:11:45 INFO - 'MAIL': '/var/mail/cltbld', 22:11:45 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:11:45 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:11:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:45 INFO - 'MOZ_NO_REMOTE': '1', 22:11:45 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:11:45 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:45 INFO - 'NO_EM_RESTART': '1', 22:11:45 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:45 INFO - 'PWD': '/builds/slave/test', 22:11:45 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:11:45 INFO - 'SHELL': '/bin/bash', 22:11:45 INFO - 'SHLVL': '1', 22:11:45 INFO - 'TERM': 'linux', 22:11:45 INFO - 'TMOUT': '86400', 22:11:45 INFO - 'USER': 'cltbld', 22:11:45 INFO - 'XDG_SESSION_COOKIE': '5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086', 22:11:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:45 INFO - '_': '/tools/buildbot/bin/python'} 22:11:45 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Inbound', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:11:45 INFO - mozversion application_buildid: 20160425193441 22:11:45 INFO - mozversion application_changeset: a96d8ef42b26cb49d156f981e2aa745eadb1f17e 22:11:45 INFO - mozversion application_display_name: Nightly 22:11:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:11:45 INFO - mozversion application_name: Firefox 22:11:45 INFO - mozversion application_remotingname: firefox 22:11:45 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:11:45 INFO - mozversion application_vendor: Mozilla 22:11:45 INFO - mozversion application_version: 49.0a1 22:11:45 INFO - mozversion platform_buildid: 20160425193441 22:11:45 INFO - mozversion platform_changeset: a96d8ef42b26cb49d156f981e2aa745eadb1f17e 22:11:45 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 22:11:45 INFO - mozversion platform_version: 49.0a1 22:11:45 INFO - using testdate: 1461647505 22:11:45 INFO - actual date: 1461647505 22:11:45 INFO - starting webserver on 'localhost:51877' 22:11:45 INFO - SUITE-START | Running 2 tests 22:11:45 INFO - TEST-START | dromaeo_css 22:11:45 INFO - operating with platform_type : linux_ 22:11:45 INFO - Initialising browser for dromaeo_css test... 22:11:45 INFO - TEST-INFO | started process 19748 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf0H7g2/profile http://localhost:51877/getInfo.html) 22:11:50 INFO - PROCESS | 19748 | __metrics Screen width/height:1600/1200 22:11:50 INFO - PROCESS | 19748 | colorDepth:24 22:11:50 INFO - PROCESS | 19748 | Browser inner width/height: 1024/697 22:11:50 INFO - PROCESS | 19748 | __metrics 22:11:52 ERROR - PROCESS | 19748 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:11:52 INFO - TEST-INFO | 19748: exit 0 22:11:52 INFO - Browser initialized. 22:11:52 INFO - Running cycle 1/1 for dromaeo_css test... 22:11:52 INFO - TEST-INFO | started process 19830 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpf0H7g2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:12:04 INFO - PROCESS | 19830 | RSS: Main: 154202112 22:12:04 INFO - PROCESS | 19830 | 22:12:55 INFO - PROCESS | 19830 | 1461647575506 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] 22:15:03 INFO - PROCESS | 19830 | Cycle 1(1): loaded http://localhost:51877/tests/dromaeo/cssquery-dojo.html (next: http://localhost:51877/tests/dromaeo/cssquery-ext.html) 22:15:04 INFO - PROCESS | 19830 | RSS: Main: 250003456 22:15:04 INFO - PROCESS | 19830 | 22:18:06 INFO - PROCESS | 19830 | Cycle 1(1): loaded http://localhost:51877/tests/dromaeo/cssquery-ext.html (next: http://localhost:51877/tests/dromaeo/cssquery-jquery.html) 22:18:07 INFO - PROCESS | 19830 | RSS: Main: 236478464 22:18:07 INFO - PROCESS | 19830 | 22:21:09 INFO - PROCESS | 19830 | Cycle 1(1): loaded http://localhost:51877/tests/dromaeo/cssquery-jquery.html (next: http://localhost:51877/tests/dromaeo/cssquery-mootools.html) 22:21:09 INFO - PROCESS | 19830 | RSS: Main: 239800320 22:21:09 INFO - PROCESS | 19830 | 22:24:11 INFO - PROCESS | 19830 | Cycle 1(1): loaded http://localhost:51877/tests/dromaeo/cssquery-mootools.html (next: http://localhost:51877/tests/dromaeo/cssquery-prototype.html) 22:24:12 INFO - PROCESS | 19830 | RSS: Main: 233771008 22:24:12 INFO - PROCESS | 19830 | 22:27:14 INFO - PROCESS | 19830 | Cycle 1(1): loaded http://localhost:51877/tests/dromaeo/cssquery-prototype.html (next: http://localhost:51877/tests/dromaeo/cssquery-yui.html) 22:27:15 INFO - PROCESS | 19830 | RSS: Main: 221593600 22:27:15 INFO - PROCESS | 19830 | 22:30:17 INFO - PROCESS | 19830 | Cycle 1(1): loaded http://localhost:51877/tests/dromaeo/cssquery-yui.html (next: http://localhost:51877/tests/dromaeo/cssquery-dojo.html) 22:30:18 INFO - PROCESS | 19830 | RSS: Main: 273002496 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | __start_tp_report 22:30:18 INFO - PROCESS | 19830 | _x_x_mozilla_page_load 22:30:18 INFO - PROCESS | 19830 | _x_x_mozilla_page_load_details 22:30:18 INFO - PROCESS | 19830 | |i|pagename|runs| 22:30:18 INFO - PROCESS | 19830 | |0;dojo.html;3108.0675797260824;3128.7027732365445;3137.3097918457947;3164.114048066541;3203.8718451261943;5228.0589493891175;5285.471452854713;5309.654872433289;5319.3084898963125;5319.6808191508535;5340.118880384732;5373.919391209125;5400.270963419937;5411.972940135303;5452.345718513784;4744.217204161309;4822.421309442863;4940.5306495883015;4972.552470277675;5006.27409025691;4914.164592019365;4955.256711493269;4997.30037794709;4998.250262460642;5009.223570346584;4711.458182309027;4779.522047795227;4839.467658557556;4899.314096026559;4905.779239934212;4855.854324370274;4857.465678775332;4862.975685121587;4905.141600219948;4914.6068314535105;4794.712317260941;4847.636427267934;4849.878753031202;4855.660103792736;4893.486183950669;5161.406438259573;5185.585153187736;5265.605079619006;5276.393214780311;5282.102042652758;5673.46102120302;5677;5680.914786278209;5684.175794509814;5686.658800471943;5727.656340619576;5742.138679198112;5745.396733343023;5749.137629355589;5750.252467179282;5220.216967454874;5294.285271488338;5342.385625653022;5363.302770639831;5399.298091248152;5787.797427090031;5791.82624521265;5798.304203495523;5800.100984347433;5854.560907931922;5487.067198576252;5620.634658747168;5625;5625.915611265835;5634.887302253932;5691.886162276731;5706.172604972256;5716.256886604715;5761.337446193657;5769.134629805479;5612.579056570774;5654.038813401731;5655.236543066716;5657.349404818498;5657.462541058593;5477.616566840283;5613.438656134354;5664;5672.347680016768;5688.089905615082;4336.262835318003;4341.131773645284;4367.541408152162;4381.167578160203;4397.340398940185;3870.3613903706137;3882.5340959085092;3885.0675837798726;3890.319194141014;3919.039835240327;5289.867753306198;5358.97320513395;5396.244525766396;5452.291202143696;5489.451054894478;5653.038983372837;5671.971640141773;5676.971615141898;5681.857953551194;5740.512056475246;5349.551958808153;5351.117065684196;5353.678779273256;5355.196720491879;5358.33020872391;4766.95233047672;4902.7058376497525;4922.92615610759;4984.601231901439;4991.7004979700605;5913.793017244376;5938.6436813791315;5944.7027648617395;5948.048312270016;5951.3751056139345;5746.5402767779315;5769.423057694197;5770.855728606818;5771.7402716877;5773.8556536086935;5659.3491748447805;5840.094785308284;5903.321118071477;5936.37668044849;5954.821355359346;5653.886922261449;5673.602847800615;5677.943220567741;5680.4319568042865;5681.573881958787;5593.944060559343;5711.685857277889;5718.942810571841;5735.254416925773;5768.394318596487;5540.806071787468;5605.467480589337;5605.691686957257;5641.351244606923;5653.236813030188;5497.835064948058;5498.367687715964;5502.2296878436255;5503.669779813224;5503.917441238465;5353.705546194997;5420.430854760351;5427.240186373831;5437.619366644297;5475.561955043508;5649.20911072442;5659.434056594308;5661.575381846295;5680.062789639664;5736.082226843685;5485.149801780731;5495.120780675073;5505.697186654773;5506.339239291311;5529.22590837275 22:30:18 INFO - PROCESS | 19830 | |1;ext.html;593.4421643654964;623.140066707943;654.116942128127;664.980050598482;672.5762769455242;30512.23719407013;30966.84516577414;31142.532862007087;31172.376552468937;36872.26255474896;38896.611033889654;39411.40882886752;39526.20947581054;39540.60459395412;39611.20777584454;8310.91689083109;8580.013298470662;8583.95708021459;8584.31325493959;8587.785305367353;8392.20274073962;8475.533845638487;8527.445716028438;8533.914660853405;8538.573071346438;8550;8774.956125219365;8799.428037177595;8799.780005499882;8802.07578204289;8679.522626255553;8708.30333573319;8746.344024198146;8766.60550275236;8772.342074344419;8461.915380846174;8578.313734901192;8579.356548258906;8583.785405364853;8583.871241931376;38236.80881595602;38511.229775404616;38898.41652375188;38962.61037389618;39049.60950390488;3459.3600183966046;3559.48387483816;3703.500027496307;3834.961650383488;4074.327735923569;29961.850190749126;30784;30819.383612327627;30898.227544311347;31150.22124446884;3979.383195604686;4004.159126583421;4004.6796256299426;4011.3782363733667;4022.4770779798732;262619;262892.9999999981;263024;263517;264603;52564.21153682698;52577.211341829905;52999.0850503226;53029.287949843914;53083.73458132685;54363;55217;55323.44676553264;55379.16931246035;55526.72236638791;15110.471133510275;15260.771088433683;15318.770218446733;15346.616334591501;15356.232188390535;11499.482523286471;11586.246893951866;11587.362695051854;11592.47833847479;11599.942000289944;17737;17850.5537361567;17914.46256612304;17914.55213619644;17972.19125139372;6816.591004539743;6903.1371078615175;7063.434925205971;7090.75182368625;7127.60798156096;944.0323668240109;1035.0839507036328;1048.7325731938458;1074.033369967036;1118.1948996722347;30908.53637195444;31565.21086972798;32487.35025299481;32490.83754581212;32736;1112.9777404451866;1131.095123900876;1149.3391300002365;1155.8324043013715;1255.5291765587904;15516.379344826306;15771.526854194393;15804.683906321809;15987.280572374271;16356.427525036566;363164;369441;370471;371037;371725;51936.74031629818;52794;52872.47127528753;52903;52935.20597190968;1223.7368965672424;1331.2411925202734;1345.9596212113652;1364.467857535562;1388.5626027801413;28055;30363;30447.84776076194;30969.380612387176;31579.84210078935;44135.55864441443;45089;45120.323195152756;45234;45270.77364613156;285078;290610;290722;291253;291855;22524.662130067733;24551.263462095452;24705.87647061753;24784.876075619508;25814.612780807925;39738.2052358957;39959.80020099881;40251.19497609973;40629.59370406258;43080.56919430765;36905.63094369129;37301.4404783923;37307.81346093252;37757.622423775414;38312;36705.816470918544;36998.07504812401;37118.8144059278;37130.257394852495;37153.07117322089;36803.81598091992;37666.24667506689;38210.617893821815;38281;38695.80652096721;32497.350052998336;33290.66709332875;33400.33199335951;33841.32317353688;34048.44636652211;26179.476410471303;26725.590277603977;26755.33111672224;26813.329666758487;26974.1907742768 22:30:18 INFO - PROCESS | 19830 | |2;jquery.html;390.90227443139213;395.59253968412526;395.6755460522373;407.2404964740758;410.685825343612;13608.32345056456;15924.840751592481;18132.637347253047;18890.90554547227;18944.431667049976;14796.6300842479;16937.237824297907;17474.21366038528;17700.911495442506;17756.556086097855;5401.4328495508;5555.416681248471;5598.384177740466;5636.859078523029;5645.379008309083;4062.8577999770014;5535.916961245564;5552.666839989613;5893.469587737103;6217.067439884031;6141.938580614204;6165.7533698652;6176.258848938133;6186.81439556814;6202.069689546559;6020.45815876571;6091.299500557426;6198.597091189104;6211.782587609435;6215.28524219716;5265.552428043621;5480.123180291174;5488.615796894219;5489.752961116759;5510.752016159283;21661.133554657794;21673.891630541904;21812.236571719914;21839.890800546054;21841.344759657237;2399.196269249803;2616.7252438494065;2654.5487267164626;3164.5253212018383;3172.6510083890753;13373.66565835852;13843.377048032762;13920.582382528439;14065.789013164811;14538.563843084725;1826.7168588868747;1962.1954998450567;1979.3468155508792;1988.3438465306415;2002.1090614676475;66565;67528;68639.3136068638;69214.3078569213;69930.30069699339;9899.703008909744;10085.647002354883;10126.240531960133;10128.442935638463;10558.102561282378;12927.224366538037;12930.935345323212;13010.349482525837;13060.738785224243;13136.474541018251;7615.124260710089;7842.176571460029;7859.331956783623;7976.242256985577;7983;6632.1046658701425;7688.769336919901;7711.884321735179;7873.606319683993;7874.251946065115;6150.415710507495;6308.369163083655;6483.513736469784;6578.8355291118105;6611.80987422259;1815.0941511413228;1884.6042331110345;1919.193938545814;1921.6444957682872;1927.8361339286037;936.8173206224857;996.5615129343211;1035.7410647338165;1125.857254886291;1183.3846399872018;17273;18122.90938545299;18369.173387197607;18833.81166188348;19162.520936976685;968.41894863083;978.8776402949632;981.239539357004;989.5250279865704;993.5876611206269;10512.842307365396;10940.12479001678;10958.671239862817;11052.502637381338;11148.60979865717;345444;350550;351233;507382;514853;74899.38254630925;79898;81560;81636.59181704173;81892;1054.541274545575;1056;1081.9459027048615;1091.7871015152127;1094.7700982793704;16473.752893706365;16777.83222167763;16850.66298674044;17011.48965531036;17404.82595174032;35088.47367289544;36742.81628591947;37474.25051499009;37475.43786843145;37560.812195938845;50381.49618503915;50613.74693126511;51095;51499.742501287255;51562.48437515727;8075.152109028468;8160.795980100545;8169.101398846004;8651.52416617092;8899.15458031486;12575.685607859878;13154.276514791396;13286.734265314833;13453.596392108253;13602.455901764215;11065.280756750786;11523.193376463567;11526.711832204262;11839.940800295943;12161.45273462714;11082.335059896432;11498.367589782642;11649.184557081262;11841.526338946345;11905.464254108412;11862.881371186522;11919.940400297943;11997.64007079789;12000.1599888007;12180.208286461351;8495.575221239036;8534.91465085366;8642.697505587274;8718.128187181232;8979.14698103679;11568.132390070947;11572.479238434122;11876.524939002344;11942.462589183682;11961.401929903295 22:30:18 INFO - PROCESS | 19830 | |3;mootools.html;1344.3681469709234;1440.2798600699648;1487.9851201487982;1609.3964763213794;1746.170568979734;7568.62156892156;7804.882926756089;7854.096778870432;7856.175101614327;7856.72501462449;7641.961790191055;7805.5706936118495;7825.13923468418;7829.765107046784;7830.491018083821;2048.0988365119374;2265.909363625453;2321.5240875620457;2325.16294134112;2361.067378385537;2282.326713619478;2287.7025986621798;2294.6902168207243;2296.276672848056;2297.2304278066867;2260.886955652219;2315.2822624986225;2325.058351367699;2327.7439481657;2327.7788610082016;2024.87850728956;2264.841461097724;2330.568844763724;2335.9649605255936;2336.976630233693;2246.8764217968005;2268.8411811173223;2271.9776100754725;2277.9658305125436;2280.669302951063;8025.478343907627;8049.03411590607;8055.516668999879;8100.8379832403025;8109.878351824727;4943.777530011158;5101.948980510221;5114.897702045975;5132.076226279269;5200.609954253446;3388.169898374914;3409.7613167078316;3463.0822831949554;3519.225770330523;3551.200979779529;1034.1881622925966;1042.1142029275131;1063.1813503602261;1065.5631191211644;1085.3921803789824;11057.834132488018;11064.446777661085;11074.833877491845;11081.889181108167;11100.722481938015;4489.416375871181;4533.274676051882;4636.142313671914;4643.907121857544;4648.2562789953445;9381.5309234538;9390.154886060287;9422.34043616954;9424.340296179338;9429.434233945985;4657.767111644404;5108.208227724708;5112.284280200774;5124.461931497139;5129.974005198975;4638.234691275901;4771.832985845479;4778.737169455644;4792.832250871203;4799.28010798383;3761.1161377076364;3785.5835858055593;3837.5394952605866;3901.3367727486398;3911.6283953024417;1217.2696382170627;1261.5458434963407;1265.7405231927432;1275.222114510148;1276.5340650662627;860.350435421253;879.5865943006777;882.060605455201;934.2339281789003;946.7917058247175;5780.393058728857;5904.380040095728;5995.640261584319;6030.668313242813;6230.626162430269;888.3692578269372;907.9319051071196;912.0833562269876;941.5998200764648;994.9303548751662;4033.979830100831;4036.1120553478186;4041.8787436376956;4071.5521292657786;4081.9387709184384;9540.284478664127;9907.207423406111;9955.153811926068;9978.700638980843;10023;9800.803983920281;9848.21214302854;9894.109530142321;9910.058544438267;9937.751556211153;7313;7582.710939140359;7601.581912994839;7654.846903061796;7752.379809615217;5767.394423585463;5771.307443106854;5933.643981361132;5951.523878089829;5975.9103613446705;9091.772705682411;9216.723498295061;9279.350445468752;9283.535823208947;9357.596360545973;9210.907890921273;9270.860937085814;9347.766305842408;9555.092266234697;9562;6448.613083215022;6866.759663411956;7175.103112110986;7219.0976127984;7342.559446433231;7724.304812566896;7743.419243556868;7802.492837965514;7807.492512986638;7828.256315650005;6974.6861391238535;7133.714651413885;7164;7238.0952380952385;7288;7025.402840758593;7081.82295442618;7088.503804733826;7163.534370265916;7229.963850180716;6434.549581529248;6806;6813;6813.1142951415995;6816.863662726619;5140.614453915897;5156.793728250828;5167.302414174189;5167.534921857051;5179.507946745053;5183.455737147545;5222.686638801684;5273.841784746463;5275.1032324505695;5349.144136938071 22:30:18 INFO - PROCESS | 19830 | |4;prototype.html;315.41647951290116;329.08022078291174;332.4896284203748;333.9181900434394;335.5704697986578;5177.792888284464;5180;5231.61362239006;5242.18746094356;5251.264822924789;5005.724685142316;5081.542661160494;5149;5193.246979188016;5289.814856480024;4129.6283334499885;4161.875143745692;4176.248275310443;4183.497980242375;4197.202531519017;4146.647534959517;4151.916961660765;4169.457970463835;4174.8121334539865;4184.309588917825;3963.623455771697;3965.940510892339;4109.835606575748;4129.525104612978;4132.933533233383;4085.7140000199997;4090.5091389033505;4098.180363927211;4101.630853223194;4118.114605359832;3645.2891686121216;3648.3068217038945;3657.7988210648405;3672.889813305605;3699.5375578052744;4888.193448081062;4930.211166213425;5038.118329292396;5052.646314757969;5070.188769796815;4653.115907977473;4671.089137618166;4692.906141877177;4715.3869996900185;4727.149113159629;5469;5473.75368108436;5484.588655850827;5505.366882808488;5508.228847961289;3955.169414422946;3972.3397219164867;4037.425404092406;4045.898852528681;4052.8581499647667;5655.632383895034;5658.773649054074;5680.147977803321;5701.173329867229;5725.656460612377;4085.305498065336;4101.671866250693;4102.630763231324;4116.670666346685;4124.237016152021;3756.549214094326;3982.900427489336;4165.83336666536;4174.770387628649;4180.728252663567;4564;4568.908621827612;4595.195840727859;4609.700369475973;4610.677252592286;5521.585881058937;5524.14375771756;5534.1975413564805;5543.47554422537;5544.63959842609;1369.760291948905;1373.7046534995025;1458.3364569121024;1475.970480590382;1476.6234610174338;966.840471322238;968.6803354892868;982.1798798003604;984.6799790068256;987.3039507147444;5530.7511161997;5537.889242215133;5545.556355491551;5554.361248456398;5591.720413979285;5948.67282299469;5955.434233747787;5963.016102343065;5994.7736845523805;6033.607815491979;5427.213054107132;5599.384067752544;5604.887902242014;5614.101743720985;5626.465485778845;3198.1365031441846;3198.920026999297;3209.1335339458215;3213.775035747475;3225.59680039995;5650.406707295757;6207.099970504251;6232.345603711545;6234.594751341148;6257.405546473078;5742.454466825645;5748.770049197985;5753.683547404933;5776.1624564437925;5802.88394232113;5851.215379309267;5883.61756485827;5903.793367232126;5910.527157827363;5921.378255283219;5602.467765562265;5670.92252472029;5690.146478028329;5724;5726.2269593606;5645.68948707825;5665.405132461032;5666.886662266649;5675.985025315953;5692.1461780733225;5639.238702775073;5919.378465261086;5920.822375328747;5926.585139040399;5958.672273025025;3979.721419500607;4030.7783071930153;4033.5764744701382;4042.8584999524874;4073.694472914484;5337.706426146599;5669.461401166883;5688.516476099578;5712.257406537124;5717.771289148387;5422.430644782399;5566.749496272759;5596.86007849807;5610.13042978339;5633;5593.196626293997;5594.797118619354;5604.327480702179;5627.396192085236;5649.13578519096;5209;5510.760078982197;5514.80698175562;5519.586031047767;5537.529310008695;5536.390996990408;5538.833834984957;5563.527100196528;5582.804601838916;5585.134304182858;5440.700761458158;5441.972790136024;5454.972490267781;5456.972715136399;5470.425605311385 22:30:18 INFO - PROCESS | 19830 | |5;yui.html;778.9221077892211;827.8592639251327;839.2782207301723;846.018618402653;848.1730312944879;12668.303243321614;12969.610911672642;13129.540466083688;13159.802602960963;13174.27541485218;13156.868431315685;13482.191068535894;13673.24797136157;13718;13729.107608005472;677.695037233247;787.9842403151933;801.1907972947307;810.8378324335127;811.9472234304781;797.9202079792033;807.1605530248564;808.107041718898;808.458332916947;808.514891065362;843.5992903370911;853.3770347646222;853.4921721575653;854.6325080215505;856.1438561438562;692.6709812838911;825.2078005115037;846.8856704344896;852.3479538153317;855.5080828523562;617.6417677746896;617.660286842235;619.9690015499252;620.2742790934617;620.4663988969482;12044.518219271218;12050.216735912225;12066.939665301705;12110.636680899586;12116.818247726203;13090.41093150801;13523.188608683511;14290.64273393163;14341.856581434156;14450.421983120663;10495.160387169008;10578.894211057868;10710.250282480229;11474.483648235766;11496.540138394454;4298.161858437606;4338.370936214263;4470.262406702923;4478.798454069574;4532.206863798855;50387.74806125946;50473.74763126198;51229.7438512805;51361;51508.484915150744;10317.535710893027;10664.146868250522;10856.565737370574;10969.725756856142;11210.775784484427;18788.530286742942;18815;18920.24319027251;18923.716144257847;18931.716024259647;8128.9593552031865;8144.674213031531;8385.706500272461;8388.454750441202;8549.444286121507;4689.929651055237;4759.619230461555;4801.783919723621;4826.710397376099;4846.07924494352;3316.121227874615;3382.1713680148027;3392.6776956189124;3395.966040339614;3398.7620866539105;513.907496650599;603.2640178981635;609.8841220168242;610.0665981049002;610.2524407600743;688.2051230828401;778.8169780101784;780.6994307191773;780.7423550228411;800.507687772016;9632.133108020313;9672.177864881563;9728;9851;9905.108540231413;701.551007355293;723.1250187273458;726.5676922231264;730.8026832755127;737.3695490355776;4450.777461126931;4562.452505699337;4589.908201835945;4608.331791890158;4613.354130421743;71919;73116.63441682865;73293.26706732971;73812.63093684497;73926;61838.69080654568;63612.68193659095;64171;65561;65972.67013664998;55046.44953550494;56600.99999999918;57372.139417908766;57568.71215643979;58317;8806.075362087146;8965;9044.864327035231;9048.366614337196;9093.318001149808;36402.45396319004;36953;37028.62971370252;37379.25241495209;37519.624803752704;47254.76372618115;50337.244941326644;50843.491565083874;51157.7442112787;52593.21110183274;11493.310401375944;11678;11695.12620102581;11823.290602563873;11962.820557691468;12769.233845969271;12785.74428511406;12821.551245706356;12927.676808079874;13108.934455327662;12165.574204902787;12378.257304561756;12463.127581069239;12831.679208019874;12944.805827912778;12171;12260.693482663004;12302.81545776832;12358.002755095944;12423.751524969632;12202.877971220174;12311.19977201479;12375.319357435428;12630.936845316082;12895.355232238539;10224.386536807815;10265.58937642486;10449;10644.254902156774;10710.196735245043;9663.9033609663;9897.752556186153;9935.65225217114;9950.55222514974;10018 22:30:18 INFO - PROCESS | 19830 | __end_tp_report 22:30:18 INFO - PROCESS | 19830 | __start_cc_report 22:30:18 INFO - PROCESS | 19830 | _x_x_mozilla_cycle_collect,793 22:30:18 INFO - PROCESS | 19830 | __end_cc_report 22:30:18 INFO - PROCESS | 19830 | __startTimestamp1461648618236__endTimestamp 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | ------- Summary: start ------- 22:30:18 INFO - PROCESS | 19830 | Number of tests: 6 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | [#0] dojo.html Cycles:165 Average:5300.36 Median:5489.45 stddev:590.47 (10.8%) stddev-sans-first:566.68 22:30:18 INFO - PROCESS | 19830 | Values: 3108.1 3128.7 3137.3 3164.1 3203.9 5228.1 5285.5 5309.7 5319.3 5319.7 5340.1 5373.9 5400.3 5412.0 5452.3 4744.2 4822.4 4940.5 4972.6 5006.3 4914.2 4955.3 4997.3 4998.3 5009.2 4711.5 4779.5 4839.5 4899.3 4905.8 4855.9 4857.5 4863.0 4905.1 4914.6 4794.7 4847.6 4849.9 4855.7 4893.5 5161.4 5185.6 5265.6 5276.4 5282.1 5673.5 5677.0 5680.9 5684.2 5686.7 5727.7 5742.1 5745.4 5749.1 5750.3 5220.2 5294.3 5342.4 5363.3 5399.3 5787.8 5791.8 5798.3 5800.1 5854.6 5487.1 5620.6 5625.0 5625.9 5634.9 5691.9 5706.2 5716.3 5761.3 5769.1 5612.6 5654.0 5655.2 5657.3 5657.5 5477.6 5613.4 5664.0 5672.3 5688.1 4336.3 4341.1 4367.5 4381.2 4397.3 3870.4 3882.5 3885.1 3890.3 3919.0 5289.9 5359.0 5396.2 5452.3 5489.5 5653.0 5672.0 5677.0 5681.9 5740.5 5349.6 5351.1 5353.7 5355.2 5358.3 4767.0 4902.7 4922.9 4984.6 4991.7 5913.8 5938.6 5944.7 5948.0 5951.4 5746.5 5769.4 5770.9 5771.7 5773.9 5659.3 5840.1 5903.3 5936.4 5954.8 5653.9 5673.6 5677.9 5680.4 5681.6 5593.9 5711.7 5718.9 5735.3 5768.4 5540.8 5605.5 5605.7 5641.4 5653.2 5497.8 5498.4 5502.2 5503.7 5503.9 5353.7 5420.4 5427.2 5437.6 5475.6 5649.2 5659.4 5661.6 5680.1 5736.1 5485.1 5495.1 5505.7 5506.3 5529.2 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | [#1] ext.html Cycles:180 Average:46992.97 Median:37124.54 stddev:81450.65 (219.4%) stddev-sans-first:81604.64 22:30:18 INFO - PROCESS | 19830 | Values: 593.4 623.1 654.1 665.0 672.6 30512.2 30966.8 31142.5 31172.4 36872.3 38896.6 39411.4 39526.2 39540.6 39611.2 8310.9 8580.0 8584.0 8584.3 8587.8 8392.2 8475.5 8527.4 8533.9 8538.6 8550.0 8775.0 8799.4 8799.8 8802.1 8679.5 8708.3 8746.3 8766.6 8772.3 8461.9 8578.3 8579.4 8583.8 8583.9 38236.8 38511.2 38898.4 38962.6 39049.6 3459.4 3559.5 3703.5 3835.0 4074.3 29961.9 30784.0 30819.4 30898.2 31150.2 3979.4 4004.2 4004.7 4011.4 4022.5 262619.0 262893.0 263024.0 263517.0 264603.0 52564.2 52577.2 52999.1 53029.3 53083.7 54363.0 55217.0 55323.4 55379.2 55526.7 15110.5 15260.8 15318.8 15346.6 15356.2 11499.5 11586.2 11587.4 11592.5 11599.9 17737.0 17850.6 17914.5 17914.6 17972.2 6816.6 6903.1 7063.4 7090.8 7127.6 944.0 1035.1 1048.7 1074.0 1118.2 30908.5 31565.2 32487.4 32490.8 32736.0 1113.0 1131.1 1149.3 1155.8 1255.5 15516.4 15771.5 15804.7 15987.3 16356.4 363164.0 369441.0 370471.0 371037.0 371725.0 51936.7 52794.0 52872.5 52903.0 52935.2 1223.7 1331.2 1346.0 1364.5 1388.6 28055.0 30363.0 30447.8 30969.4 31579.8 44135.6 45089.0 45120.3 45234.0 45270.8 285078.0 290610.0 290722.0 291253.0 291855.0 22524.7 24551.3 24705.9 24784.9 25814.6 39738.2 39959.8 40251.2 40629.6 43080.6 36905.6 37301.4 37307.8 37757.6 38312.0 36705.8 36998.1 37118.8 37130.3 37153.1 36803.8 37666.2 38210.6 38281.0 38695.8 32497.4 33290.7 33400.3 33841.3 34048.4 26179.5 26725.6 26755.3 26813.3 26974.2 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | [#2] jquery.html Cycles:180 Average:25758.47 Median:3168.59 stddev:69367.43 (2189.2%) stddev-sans-first:69535.88 22:30:18 INFO - PROCESS | 19830 | Values: 390.9 395.6 395.7 407.2 410.7 13608.3 15924.8 18132.6 18890.9 18944.4 14796.6 16937.2 17474.2 17700.9 17756.6 5401.4 5555.4 5598.4 5636.9 5645.4 4062.9 5535.9 5552.7 5893.5 6217.1 6141.9 6165.8 6176.3 6186.8 6202.1 6020.5 6091.3 6198.6 6211.8 6215.3 5265.6 5480.1 5488.6 5489.8 5510.8 21661.1 21673.9 21812.2 21839.9 21841.3 2399.2 2616.7 2654.5 3164.5 3172.7 13373.7 13843.4 13920.6 14065.8 14538.6 1826.7 1962.2 1979.3 1988.3 2002.1 66565.0 67528.0 68639.3 69214.3 69930.3 9899.7 10085.6 10126.2 10128.4 10558.1 12927.2 12930.9 13010.3 13060.7 13136.5 7615.1 7842.2 7859.3 7976.2 7983.0 6632.1 7688.8 7711.9 7873.6 7874.3 6150.4 6308.4 6483.5 6578.8 6611.8 1815.1 1884.6 1919.2 1921.6 1927.8 936.8 996.6 1035.7 1125.9 1183.4 17273.0 18122.9 18369.2 18833.8 19162.5 968.4 978.9 981.2 989.5 993.6 10512.8 10940.1 10958.7 11052.5 11148.6 345444.0 350550.0 351233.0 507382.0 514853.0 74899.4 79898.0 81560.0 81636.6 81892.0 1054.5 1056.0 1081.9 1091.8 1094.8 16473.8 16777.8 16850.7 17011.5 17404.8 35088.5 36742.8 37474.3 37475.4 37560.8 50381.5 50613.7 51095.0 51499.7 51562.5 8075.2 8160.8 8169.1 8651.5 8899.2 12575.7 13154.3 13286.7 13453.6 13602.5 11065.3 11523.2 11526.7 11839.9 12161.5 11082.3 11498.4 11649.2 11841.5 11905.5 11862.9 11919.9 11997.6 12000.2 12180.2 8495.6 8534.9 8642.7 8718.1 8979.1 11568.1 11572.5 11876.5 11942.5 11961.4 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | [#3] mootools.html Cycles:180 Average:5447.20 Median:5558.27 stddev:2936.47 (52.8%) stddev-sans-first:2928.52 22:30:18 INFO - PROCESS | 19830 | Values: 1344.4 1440.3 1488.0 1609.4 1746.2 7568.6 7804.9 7854.1 7856.2 7856.7 7642.0 7805.6 7825.1 7829.8 7830.5 2048.1 2265.9 2321.5 2325.2 2361.1 2282.3 2287.7 2294.7 2296.3 2297.2 2260.9 2315.3 2325.1 2327.7 2327.8 2024.9 2264.8 2330.6 2336.0 2337.0 2246.9 2268.8 2272.0 2278.0 2280.7 8025.5 8049.0 8055.5 8100.8 8109.9 4943.8 5101.9 5114.9 5132.1 5200.6 3388.2 3409.8 3463.1 3519.2 3551.2 1034.2 1042.1 1063.2 1065.6 1085.4 11057.8 11064.4 11074.8 11081.9 11100.7 4489.4 4533.3 4636.1 4643.9 4648.3 9381.5 9390.2 9422.3 9424.3 9429.4 4657.8 5108.2 5112.3 5124.5 5130.0 4638.2 4771.8 4778.7 4792.8 4799.3 3761.1 3785.6 3837.5 3901.3 3911.6 1217.3 1261.5 1265.7 1275.2 1276.5 860.4 879.6 882.1 934.2 946.8 5780.4 5904.4 5995.6 6030.7 6230.6 888.4 907.9 912.1 941.6 994.9 4034.0 4036.1 4041.9 4071.6 4081.9 9540.3 9907.2 9955.2 9978.7 10023.0 9800.8 9848.2 9894.1 9910.1 9937.8 7313.0 7582.7 7601.6 7654.8 7752.4 5767.4 5771.3 5933.6 5951.5 5975.9 9091.8 9216.7 9279.4 9283.5 9357.6 9210.9 9270.9 9347.8 9555.1 9562.0 6448.6 6866.8 7175.1 7219.1 7342.6 7724.3 7743.4 7802.5 7807.5 7828.3 6974.7 7133.7 7164.0 7238.1 7288.0 7025.4 7081.8 7088.5 7163.5 7230.0 6434.5 6806.0 6813.0 6813.1 6816.9 5140.6 5156.8 5167.3 5167.5 5179.5 5183.5 5222.7 5273.8 5275.1 5349.1 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | [#4] prototype.html Cycles:180 Average:4695.46 Median:5448.47 stddev:1381.16 (25.3%) stddev-sans-first:1345.34 22:30:18 INFO - PROCESS | 19830 | Values: 315.4 329.1 332.5 333.9 335.6 5177.8 5180.0 5231.6 5242.2 5251.3 5005.7 5081.5 5149.0 5193.2 5289.8 4129.6 4161.9 4176.2 4183.5 4197.2 4146.6 4151.9 4169.5 4174.8 4184.3 3963.6 3965.9 4109.8 4129.5 4132.9 4085.7 4090.5 4098.2 4101.6 4118.1 3645.3 3648.3 3657.8 3672.9 3699.5 4888.2 4930.2 5038.1 5052.6 5070.2 4653.1 4671.1 4692.9 4715.4 4727.1 5469.0 5473.8 5484.6 5505.4 5508.2 3955.2 3972.3 4037.4 4045.9 4052.9 5655.6 5658.8 5680.1 5701.2 5725.7 4085.3 4101.7 4102.6 4116.7 4124.2 3756.5 3982.9 4165.8 4174.8 4180.7 4564.0 4568.9 4595.2 4609.7 4610.7 5521.6 5524.1 5534.2 5543.5 5544.6 1369.8 1373.7 1458.3 1476.0 1476.6 966.8 968.7 982.2 984.7 987.3 5530.8 5537.9 5545.6 5554.4 5591.7 5948.7 5955.4 5963.0 5994.8 6033.6 5427.2 5599.4 5604.9 5614.1 5626.5 3198.1 3198.9 3209.1 3213.8 3225.6 5650.4 6207.1 6232.3 6234.6 6257.4 5742.5 5748.8 5753.7 5776.2 5802.9 5851.2 5883.6 5903.8 5910.5 5921.4 5602.5 5670.9 5690.1 5724.0 5726.2 5645.7 5665.4 5666.9 5676.0 5692.1 5639.2 5919.4 5920.8 5926.6 5958.7 3979.7 4030.8 4033.6 4042.9 4073.7 5337.7 5669.5 5688.5 5712.3 5717.8 5422.4 5566.7 5596.9 5610.1 5633.0 5593.2 5594.8 5604.3 5627.4 5649.1 5209.0 5510.8 5514.8 5519.6 5537.5 5536.4 5538.8 5563.5 5582.8 5585.1 5440.7 5442.0 5455.0 5457.0 5470.4 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - PROCESS | 19830 | [#5] yui.html Cycles:180 Average:15539.89 Median:4836.39 stddev:19166.98 (396.3%) stddev-sans-first:19188.70 22:30:18 INFO - PROCESS | 19830 | Values: 778.9 827.9 839.3 846.0 848.2 12668.3 12969.6 13129.5 13159.8 13174.3 13156.9 13482.2 13673.2 13718.0 13729.1 677.7 788.0 801.2 810.8 811.9 797.9 807.2 808.1 808.5 808.5 843.6 853.4 853.5 854.6 856.1 692.7 825.2 846.9 852.3 855.5 617.6 617.7 620.0 620.3 620.5 12044.5 12050.2 12066.9 12110.6 12116.8 13090.4 13523.2 14290.6 14341.9 14450.4 10495.2 10578.9 10710.3 11474.5 11496.5 4298.2 4338.4 4470.3 4478.8 4532.2 50387.7 50473.7 51229.7 51361.0 51508.5 10317.5 10664.1 10856.6 10969.7 11210.8 18788.5 18815.0 18920.2 18923.7 18931.7 8129.0 8144.7 8385.7 8388.5 8549.4 4689.9 4759.6 4801.8 4826.7 4846.1 3316.1 3382.2 3392.7 3396.0 3398.8 513.9 603.3 609.9 610.1 610.3 688.2 778.8 780.7 780.7 800.5 9632.1 9672.2 9728.0 9851.0 9905.1 701.6 723.1 726.6 730.8 737.4 4450.8 4562.5 4589.9 4608.3 4613.4 71919.0 73116.6 73293.3 73812.6 73926.0 61838.7 63612.7 64171.0 65561.0 65972.7 55046.4 56601.0 57372.1 57568.7 58317.0 8806.1 8965.0 9044.9 9048.4 9093.3 36402.5 36953.0 37028.6 37379.3 37519.6 47254.8 50337.2 50843.5 51157.7 52593.2 11493.3 11678.0 11695.1 11823.3 11962.8 12769.2 12785.7 12821.6 12927.7 13108.9 12165.6 12378.3 12463.1 12831.7 12944.8 12171.0 12260.7 12302.8 12358.0 12423.8 12202.9 12311.2 12375.3 12630.9 12895.4 10224.4 10265.6 10449.0 10644.3 10710.2 9663.9 9897.8 9935.7 9950.6 10018.0 22:30:18 INFO - PROCESS | 19830 | -------- Summary: end -------- 22:30:18 INFO - PROCESS | 19830 | 22:30:18 INFO - TEST-INFO | 19830: exit 0 22:30:18 INFO - TEST-OK | dromaeo_css | took 1113651ms 22:30:18 INFO - TEST-START | kraken 22:30:18 INFO - operating with platform_type : linux_ 22:30:18 INFO - Initialising browser for kraken test... 22:30:18 INFO - TEST-INFO | started process 20013 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphEKW9c/profile http://localhost:51877/getInfo.html) 22:30:24 INFO - PROCESS | 20013 | __metrics Screen width/height:1600/1200 22:30:24 INFO - PROCESS | 20013 | colorDepth:24 22:30:24 INFO - PROCESS | 20013 | Browser inner width/height: 1024/697 22:30:24 INFO - PROCESS | 20013 | __metrics 22:30:25 ERROR - PROCESS | 20013 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:30:26 INFO - TEST-INFO | 20013: exit 0 22:30:26 INFO - Browser initialized. 22:30:26 INFO - Running cycle 1/1 for kraken test... 22:30:26 INFO - TEST-INFO | started process 20093 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmphEKW9c/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:38 INFO - PROCESS | 20093 | RSS: Main: 153833472 22:30:38 INFO - PROCESS | 20093 | 22:30:40 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:51877/tests/kraken/driver.html?testName=audio-beat-detection) 22:30:41 INFO - PROCESS | 20093 | RSS: Main: 208728064 22:30:41 INFO - PROCESS | 20093 | 22:30:44 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:51877/tests/kraken/driver.html?testName=audio-dft) 22:30:45 INFO - PROCESS | 20093 | RSS: Main: 194621440 22:30:45 INFO - PROCESS | 20093 | 22:30:48 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:51877/tests/kraken/driver.html?testName=audio-fft) 22:30:48 INFO - PROCESS | 20093 | RSS: Main: 265805824 22:30:48 INFO - PROCESS | 20093 | 22:30:50 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:51877/tests/kraken/driver.html?testName=audio-oscillator) 22:30:51 INFO - PROCESS | 20093 | RSS: Main: 173977600 22:30:51 INFO - PROCESS | 20093 | 22:30:53 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:51877/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:30:54 INFO - PROCESS | 20093 | RSS: Main: 176979968 22:30:54 INFO - PROCESS | 20093 | 22:30:57 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:51877/tests/kraken/driver.html?testName=imaging-darkroom) 22:30:57 INFO - PROCESS | 20093 | RSS: Main: 214085632 22:30:57 INFO - PROCESS | 20093 | 22:31:01 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:51877/tests/kraken/driver.html?testName=imaging-desaturate) 22:31:01 INFO - PROCESS | 20093 | RSS: Main: 215207936 22:31:01 INFO - PROCESS | 20093 | 22:31:04 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:51877/tests/kraken/driver.html?testName=json-parse-financial) 22:31:05 INFO - PROCESS | 20093 | RSS: Main: 214843392 22:31:05 INFO - PROCESS | 20093 | 22:31:06 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:51877/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:31:07 INFO - PROCESS | 20093 | RSS: Main: 202874880 22:31:07 INFO - PROCESS | 20093 | 22:31:08 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:31:09 INFO - PROCESS | 20093 | RSS: Main: 203456512 22:31:09 INFO - PROCESS | 20093 | 22:31:11 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:31:12 INFO - PROCESS | 20093 | RSS: Main: 215519232 22:31:12 INFO - PROCESS | 20093 | 22:31:14 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:31:15 INFO - PROCESS | 20093 | RSS: Main: 223580160 22:31:15 INFO - PROCESS | 20093 | 22:31:18 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:31:19 INFO - PROCESS | 20093 | RSS: Main: 220418048 22:31:19 INFO - PROCESS | 20093 | 22:31:20 INFO - PROCESS | 20093 | Cycle 1(1): loaded http://localhost:51877/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:51877/tests/kraken/driver.html?testName=ai-astar) 22:31:21 INFO - PROCESS | 20093 | RSS: Main: 283070464 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | __start_tp_report 22:31:21 INFO - PROCESS | 20093 | _x_x_mozilla_page_load 22:31:21 INFO - PROCESS | 20093 | _x_x_mozilla_page_load_details 22:31:21 INFO - PROCESS | 20093 | |i|pagename|runs| 22:31:21 INFO - PROCESS | 20093 | |0;ai-astar;111;112;107;110;110;113;104;112;111;109 22:31:21 INFO - PROCESS | 20093 | |1;audio-beat-detection;155;127;159;178;134;152;157;136;184;130 22:31:21 INFO - PROCESS | 20093 | |2;audio-dft;162;161;158;158;159;159;160;160;162;160 22:31:21 INFO - PROCESS | 20093 | |3;audio-fft;76;76;117;106;74;72;76;116;107;76 22:31:21 INFO - PROCESS | 20093 | |4;audio-oscillator;93;93;150;92;93;92;149;92;92;141 22:31:21 INFO - PROCESS | 20093 | |5;imaging-gaussian-blur;117;117;116;117;117;117;116;117;116;117 22:31:21 INFO - PROCESS | 20093 | |6;imaging-darkroom;145;146;146;145;145;146;146;145;145;145 22:31:21 INFO - PROCESS | 20093 | |7;imaging-desaturate;95;94;94;95;95;95;94;95;95;95 22:31:21 INFO - PROCESS | 20093 | |8;json-parse-financial;57;58;58;57;58;57;57;74;56;58 22:31:21 INFO - PROCESS | 20093 | |9;json-stringify-tinderbox;63;62;62;62;63;62;63;63;62;62 22:31:21 INFO - PROCESS | 20093 | |10;stanford-crypto-aes;78;78;77;78;78;78;78;78;78;79 22:31:21 INFO - PROCESS | 20093 | |11;stanford-crypto-ccm;127;129;127;126;128;131;135;134;170;128 22:31:21 INFO - PROCESS | 20093 | |12;stanford-crypto-pbkdf2;201;205;206;201;202;205;199;205;200;204 22:31:21 INFO - PROCESS | 20093 | |13;stanford-crypto-sha256-iterative;71;72;70;70;69;68;69;71;68;67 22:31:21 INFO - PROCESS | 20093 | __end_tp_report 22:31:21 INFO - PROCESS | 20093 | __start_cc_report 22:31:21 INFO - PROCESS | 20093 | _x_x_mozilla_cycle_collect,1629 22:31:21 INFO - PROCESS | 20093 | __end_cc_report 22:31:21 INFO - PROCESS | 20093 | __startTimestamp1461648681188__endTimestamp 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | ------- Summary: start ------- 22:31:21 INFO - PROCESS | 20093 | Number of tests: 14 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#0] ai-astar Cycles:10 Average:109.90 Median:111.00 stddev:2.69 (2.4%) stddev-sans-first:2.82 22:31:21 INFO - PROCESS | 20093 | Values: 111.0 112.0 107.0 110.0 110.0 113.0 104.0 112.0 111.0 109.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#1] audio-beat-detection Cycles:10 Average:151.20 Median:156.00 stddev:19.62 (12.6%) stddev-sans-first:20.77 22:31:21 INFO - PROCESS | 20093 | Values: 155.0 127.0 159.0 178.0 134.0 152.0 157.0 136.0 184.0 130.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#2] audio-dft Cycles:10 Average:159.90 Median:160.00 stddev:1.45 (0.9%) stddev-sans-first:1.32 22:31:21 INFO - PROCESS | 20093 | Values: 162.0 161.0 158.0 158.0 159.0 159.0 160.0 160.0 162.0 160.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#3] audio-fft Cycles:10 Average:89.60 Median:75.00 stddev:19.18 (25.6%) stddev-sans-first:19.71 22:31:21 INFO - PROCESS | 20093 | Values: 76.0 76.0 117.0 106.0 74.0 72.0 76.0 116.0 107.0 76.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#4] audio-oscillator Cycles:10 Average:108.70 Median:92.00 stddev:26.31 (28.6%) stddev-sans-first:27.28 22:31:21 INFO - PROCESS | 20093 | Values: 93.0 93.0 150.0 92.0 93.0 92.0 149.0 92.0 92.0 141.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#5] imaging-gaussian-blur Cycles:10 Average:116.70 Median:117.00 stddev:0.48 (0.4%) stddev-sans-first:0.50 22:31:21 INFO - PROCESS | 20093 | Values: 117.0 117.0 116.0 117.0 117.0 117.0 116.0 117.0 116.0 117.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#6] imaging-darkroom Cycles:10 Average:145.40 Median:145.50 stddev:0.52 (0.4%) stddev-sans-first:0.53 22:31:21 INFO - PROCESS | 20093 | Values: 145.0 146.0 146.0 145.0 145.0 146.0 146.0 145.0 145.0 145.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#7] imaging-desaturate Cycles:10 Average:94.70 Median:95.00 stddev:0.48 (0.5%) stddev-sans-first:0.50 22:31:21 INFO - PROCESS | 20093 | Values: 95.0 94.0 94.0 95.0 95.0 95.0 94.0 95.0 95.0 95.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#8] json-parse-financial Cycles:10 Average:59.00 Median:58.00 stddev:5.31 (9.2%) stddev-sans-first:5.59 22:31:21 INFO - PROCESS | 20093 | Values: 57.0 58.0 58.0 57.0 58.0 57.0 57.0 74.0 56.0 58.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#9] json-stringify-tinderbox Cycles:10 Average:62.40 Median:62.50 stddev:0.52 (0.8%) stddev-sans-first:0.50 22:31:21 INFO - PROCESS | 20093 | Values: 63.0 62.0 62.0 62.0 63.0 62.0 63.0 63.0 62.0 62.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#10] stanford-crypto-aes Cycles:10 Average:78.00 Median:78.00 stddev:0.47 (0.6%) stddev-sans-first:0.50 22:31:21 INFO - PROCESS | 20093 | Values: 78.0 78.0 77.0 78.0 78.0 78.0 78.0 78.0 78.0 79.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#11] stanford-crypto-ccm Cycles:10 Average:133.50 Median:130.00 stddev:13.18 (10.1%) stddev-sans-first:13.76 22:31:21 INFO - PROCESS | 20093 | Values: 127.0 129.0 127.0 126.0 128.0 131.0 135.0 134.0 170.0 128.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:202.80 Median:204.50 stddev:2.49 (1.2%) stddev-sans-first:2.55 22:31:21 INFO - PROCESS | 20093 | Values: 201.0 205.0 206.0 201.0 202.0 205.0 199.0 205.0 200.0 204.0 22:31:21 INFO - PROCESS | 20093 | 22:31:21 INFO - PROCESS | 20093 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.58 (2.3%) stddev-sans-first:1.58 22:31:21 INFO - PROCESS | 20093 | Values: 71.0 72.0 70.0 70.0 69.0 68.0 69.0 71.0 68.0 67.0 22:31:21 INFO - PROCESS | 20093 | -------- Summary: end -------- 22:31:21 INFO - PROCESS | 20093 | 22:31:22 INFO - TEST-INFO | 20093: exit 0 22:31:22 INFO - TEST-OK | kraken | took 63185ms 22:31:22 INFO - SUITE-END | took 1176s 22:31:22 INFO - Completed test suite (00:19:37) 22:31:22 INFO - javascript benchmark 22:31:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.0675797260824, 3128.7027732365445, 3137.3097918457947, 3164.114048066541, 3203.8718451261943, 5228.0589493891175, 5285.471452854713, 5309.654872433289, 5319.3084898963125, 5319.6808191508535, 5340.118880384732, 5373.919391209125, 5400.270963419937, 5411.972940135303, 5452.345718513784, 4744.217204161309, 4822.421309442863, 4940.5306495883015, 4972.552470277675, 5006.27409025691, 4914.164592019365, 4955.256711493269, 4997.30037794709, 4998.250262460642, 5009.223570346584, 4711.458182309027, 4779.522047795227, 4839.467658557556, 4899.314096026559, 4905.779239934212, 4855.854324370274, 4857.465678775332, 4862.975685121587, 4905.141600219948, 4914.6068314535105, 4794.712317260941, 4847.636427267934, 4849.878753031202, 4855.660103792736, 4893.486183950669, 5161.406438259573, 5185.585153187736, 5265.605079619006, 5276.393214780311, 5282.102042652758, 5673.46102120302, 5677.0, 5680.914786278209, 5684.175794509814, 5686.658800471943, 5727.656340619576, 5742.138679198112, 5745.396733343023, 5749.137629355589, 5750.252467179282, 5220.216967454874, 5294.285271488338, 5342.385625653022, 5363.302770639831, 5399.298091248152, 5787.797427090031, 5791.82624521265, 5798.304203495523, 5800.100984347433, 5854.560907931922, 5487.067198576252, 5620.634658747168, 5625.0, 5625.915611265835, 5634.887302253932, 5691.886162276731, 5706.172604972256, 5716.256886604715, 5761.337446193657, 5769.134629805479, 5612.579056570774, 5654.038813401731, 5655.236543066716, 5657.349404818498, 5657.462541058593, 5477.616566840283, 5613.438656134354, 5664.0, 5672.347680016768, 5688.089905615082, 4336.262835318003, 4341.131773645284, 4367.541408152162, 4381.167578160203, 4397.340398940185, 3870.3613903706137, 3882.5340959085092, 3885.0675837798726, 3890.319194141014, 3919.039835240327, 5289.867753306198, 5358.97320513395, 5396.244525766396, 5452.291202143696, 5489.451054894478, 5653.038983372837, 5671.971640141773, 5676.971615141898, 5681.857953551194, 5740.512056475246, 5349.551958808153, 5351.117065684196, 5353.678779273256, 5355.196720491879, 5358.33020872391, 4766.95233047672, 4902.7058376497525, 4922.92615610759, 4984.601231901439, 4991.7004979700605, 5913.793017244376, 5938.6436813791315, 5944.7027648617395, 5948.048312270016, 5951.3751056139345, 5746.5402767779315, 5769.423057694197, 5770.855728606818, 5771.7402716877, 5773.8556536086935, 5659.3491748447805, 5840.094785308284, 5903.321118071477, 5936.37668044849, 5954.821355359346, 5653.886922261449, 5673.602847800615, 5677.943220567741, 5680.4319568042865, 5681.573881958787, 5593.944060559343, 5711.685857277889, 5718.942810571841, 5735.254416925773, 5768.394318596487, 5540.806071787468, 5605.467480589337, 5605.691686957257, 5641.351244606923, 5653.236813030188, 5497.835064948058, 5498.367687715964, 5502.2296878436255, 5503.669779813224, 5503.917441238465, 5353.705546194997, 5420.430854760351, 5427.240186373831, 5437.619366644297, 5475.561955043508, 5649.20911072442, 5659.434056594308, 5661.575381846295, 5680.062789639664, 5736.082226843685, 5485.149801780731, 5495.120780675073, 5505.697186654773, 5506.339239291311, 5529.22590837275], "value": 5261.465970784626, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.4421643654964, 623.140066707943, 654.116942128127, 664.980050598482, 672.5762769455242, 30512.23719407013, 30966.84516577414, 31142.532862007087, 31172.376552468937, 36872.26255474896, 38896.611033889654, 39411.40882886752, 39526.20947581054, 39540.60459395412, 39611.20777584454, 8310.91689083109, 8580.013298470662, 8583.95708021459, 8584.31325493959, 8587.785305367353, 8392.20274073962, 8475.533845638487, 8527.445716028438, 8533.914660853405, 8538.573071346438, 8550.0, 8774.956125219365, 8799.428037177595, 8799.780005499882, 8802.07578204289, 8679.522626255553, 8708.30333573319, 8746.344024198146, 8766.60550275236, 8772.342074344419, 8461.915380846174, 8578.313734901192, 8579.356548258906, 8583.785405364853, 8583.871241931376, 38236.80881595602, 38511.229775404616, 38898.41652375188, 38962.61037389618, 39049.60950390488, 3459.3600183966046, 3559.48387483816, 3703.500027496307, 3834.961650383488, 4074.327735923569, 29961.850190749126, 30784.0, 30819.383612327627, 30898.227544311347, 31150.22124446884, 3979.383195604686, 4004.159126583421, 4004.6796256299426, 4011.3782363733667, 4022.4770779798732, 262619.0, 262892.9999999981, 263024.0, 263517.0, 264603.0, 52564.21153682698, 52577.211341829905, 52999.0850503226, 53029.287949843914, 53083.73458132685, 54363.0, 55217.0, 55323.44676553264, 55379.16931246035, 55526.72236638791, 15110.471133510275, 15260.771088433683, 15318.770218446733, 15346.616334591501, 15356.232188390535, 11499.482523286471, 11586.246893951866, 11587.362695051854, 11592.47833847479, 11599.942000289944, 17737.0, 17850.5537361567, 17914.46256612304, 17914.55213619644, 17972.19125139372, 6816.591004539743, 6903.1371078615175, 7063.434925205971, 7090.75182368625, 7127.60798156096, 944.0323668240109, 1035.0839507036328, 1048.7325731938458, 1074.033369967036, 1118.1948996722347, 30908.53637195444, 31565.21086972798, 32487.35025299481, 32490.83754581212, 32736.0, 1112.9777404451866, 1131.095123900876, 1149.3391300002365, 1155.8324043013715, 1255.5291765587904, 15516.379344826306, 15771.526854194393, 15804.683906321809, 15987.280572374271, 16356.427525036566, 363164.0, 369441.0, 370471.0, 371037.0, 371725.0, 51936.74031629818, 52794.0, 52872.47127528753, 52903.0, 52935.20597190968, 1223.7368965672424, 1331.2411925202734, 1345.9596212113652, 1364.467857535562, 1388.5626027801413, 28055.0, 30363.0, 30447.84776076194, 30969.380612387176, 31579.84210078935, 44135.55864441443, 45089.0, 45120.323195152756, 45234.0, 45270.77364613156, 285078.0, 290610.0, 290722.0, 291253.0, 291855.0, 22524.662130067733, 24551.263462095452, 24705.87647061753, 24784.876075619508, 25814.612780807925, 39738.2052358957, 39959.80020099881, 40251.19497609973, 40629.59370406258, 43080.56919430765, 36905.63094369129, 37301.4404783923, 37307.81346093252, 37757.622423775414, 38312.0, 36705.816470918544, 36998.07504812401, 37118.8144059278, 37130.257394852495, 37153.07117322089, 36803.81598091992, 37666.24667506689, 38210.617893821815, 38281.0, 38695.80652096721, 32497.350052998336, 33290.66709332875, 33400.33199335951, 33841.32317353688, 34048.44636652211, 26179.476410471303, 26725.590277603977, 26755.33111672224, 26813.329666758487, 26974.1907742768], "value": 18403.83321133269, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.90227443139213, 395.59253968412526, 395.6755460522373, 407.2404964740758, 410.685825343612, 13608.32345056456, 15924.840751592481, 18132.637347253047, 18890.90554547227, 18944.431667049976, 14796.6300842479, 16937.237824297907, 17474.21366038528, 17700.911495442506, 17756.556086097855, 5401.4328495508, 5555.416681248471, 5598.384177740466, 5636.859078523029, 5645.379008309083, 4062.8577999770014, 5535.916961245564, 5552.666839989613, 5893.469587737103, 6217.067439884031, 6141.938580614204, 6165.7533698652, 6176.258848938133, 6186.81439556814, 6202.069689546559, 6020.45815876571, 6091.299500557426, 6198.597091189104, 6211.782587609435, 6215.28524219716, 5265.552428043621, 5480.123180291174, 5488.615796894219, 5489.752961116759, 5510.752016159283, 21661.133554657794, 21673.891630541904, 21812.236571719914, 21839.890800546054, 21841.344759657237, 2399.196269249803, 2616.7252438494065, 2654.5487267164626, 3164.5253212018383, 3172.6510083890753, 13373.66565835852, 13843.377048032762, 13920.582382528439, 14065.789013164811, 14538.563843084725, 1826.7168588868747, 1962.1954998450567, 1979.3468155508792, 1988.3438465306415, 2002.1090614676475, 66565.0, 67528.0, 68639.3136068638, 69214.3078569213, 69930.30069699339, 9899.703008909744, 10085.647002354883, 10126.240531960133, 10128.442935638463, 10558.102561282378, 12927.224366538037, 12930.935345323212, 13010.349482525837, 13060.738785224243, 13136.474541018251, 7615.124260710089, 7842.176571460029, 7859.331956783623, 7976.242256985577, 7983.0, 6632.1046658701425, 7688.769336919901, 7711.884321735179, 7873.606319683993, 7874.251946065115, 6150.415710507495, 6308.369163083655, 6483.513736469784, 6578.8355291118105, 6611.80987422259, 1815.0941511413228, 1884.6042331110345, 1919.193938545814, 1921.6444957682872, 1927.8361339286037, 936.8173206224857, 996.5615129343211, 1035.7410647338165, 1125.857254886291, 1183.3846399872018, 17273.0, 18122.90938545299, 18369.173387197607, 18833.81166188348, 19162.520936976685, 968.41894863083, 978.8776402949632, 981.239539357004, 989.5250279865704, 993.5876611206269, 10512.842307365396, 10940.12479001678, 10958.671239862817, 11052.502637381338, 11148.60979865717, 345444.0, 350550.0, 351233.0, 507382.0, 514853.0, 74899.38254630925, 79898.0, 81560.0, 81636.59181704173, 81892.0, 1054.541274545575, 1056.0, 1081.9459027048615, 1091.7871015152127, 1094.7700982793704, 16473.752893706365, 16777.83222167763, 16850.66298674044, 17011.48965531036, 17404.82595174032, 35088.47367289544, 36742.81628591947, 37474.25051499009, 37475.43786843145, 37560.812195938845, 50381.49618503915, 50613.74693126511, 51095.0, 51499.742501287255, 51562.48437515727, 8075.152109028468, 8160.795980100545, 8169.101398846004, 8651.52416617092, 8899.15458031486, 12575.685607859878, 13154.276514791396, 13286.734265314833, 13453.596392108253, 13602.455901764215, 11065.280756750786, 11523.193376463567, 11526.711832204262, 11839.940800295943, 12161.45273462714, 11082.335059896432, 11498.367589782642, 11649.184557081262, 11841.526338946345, 11905.464254108412, 11862.881371186522, 11919.940400297943, 11997.64007079789, 12000.1599888007, 12180.208286461351, 8495.575221239036, 8534.91465085366, 8642.697505587274, 8718.128187181232, 8979.14698103679, 11568.132390070947, 11572.479238434122, 11876.524939002344, 11942.462589183682, 11961.401929903295], "value": 9104.766663402419, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1344.3681469709234, 1440.2798600699648, 1487.9851201487982, 1609.3964763213794, 1746.170568979734, 7568.62156892156, 7804.882926756089, 7854.096778870432, 7856.175101614327, 7856.72501462449, 7641.961790191055, 7805.5706936118495, 7825.13923468418, 7829.765107046784, 7830.491018083821, 2048.0988365119374, 2265.909363625453, 2321.5240875620457, 2325.16294134112, 2361.067378385537, 2282.326713619478, 2287.7025986621798, 2294.6902168207243, 2296.276672848056, 2297.2304278066867, 2260.886955652219, 2315.2822624986225, 2325.058351367699, 2327.7439481657, 2327.7788610082016, 2024.87850728956, 2264.841461097724, 2330.568844763724, 2335.9649605255936, 2336.976630233693, 2246.8764217968005, 2268.8411811173223, 2271.9776100754725, 2277.9658305125436, 2280.669302951063, 8025.478343907627, 8049.03411590607, 8055.516668999879, 8100.8379832403025, 8109.878351824727, 4943.777530011158, 5101.948980510221, 5114.897702045975, 5132.076226279269, 5200.609954253446, 3388.169898374914, 3409.7613167078316, 3463.0822831949554, 3519.225770330523, 3551.200979779529, 1034.1881622925966, 1042.1142029275131, 1063.1813503602261, 1065.5631191211644, 1085.3921803789824, 11057.834132488018, 11064.446777661085, 11074.833877491845, 11081.889181108167, 11100.722481938015, 4489.416375871181, 4533.274676051882, 4636.142313671914, 4643.907121857544, 4648.2562789953445, 9381.5309234538, 9390.154886060287, 9422.34043616954, 9424.340296179338, 9429.434233945985, 4657.767111644404, 5108.208227724708, 5112.284280200774, 5124.461931497139, 5129.974005198975, 4638.234691275901, 4771.832985845479, 4778.737169455644, 4792.832250871203, 4799.28010798383, 3761.1161377076364, 3785.5835858055593, 3837.5394952605866, 3901.3367727486398, 3911.6283953024417, 1217.2696382170627, 1261.5458434963407, 1265.7405231927432, 1275.222114510148, 1276.5340650662627, 860.350435421253, 879.5865943006777, 882.060605455201, 934.2339281789003, 946.7917058247175, 5780.393058728857, 5904.380040095728, 5995.640261584319, 6030.668313242813, 6230.626162430269, 888.3692578269372, 907.9319051071196, 912.0833562269876, 941.5998200764648, 994.9303548751662, 4033.979830100831, 4036.1120553478186, 4041.8787436376956, 4071.5521292657786, 4081.9387709184384, 9540.284478664127, 9907.207423406111, 9955.153811926068, 9978.700638980843, 10023.0, 9800.803983920281, 9848.21214302854, 9894.109530142321, 9910.058544438267, 9937.751556211153, 7313.0, 7582.710939140359, 7601.581912994839, 7654.846903061796, 7752.379809615217, 5767.394423585463, 5771.307443106854, 5933.643981361132, 5951.523878089829, 5975.9103613446705, 9091.772705682411, 9216.723498295061, 9279.350445468752, 9283.535823208947, 9357.596360545973, 9210.907890921273, 9270.860937085814, 9347.766305842408, 9555.092266234697, 9562.0, 6448.613083215022, 6866.759663411956, 7175.103112110986, 7219.0976127984, 7342.559446433231, 7724.304812566896, 7743.419243556868, 7802.492837965514, 7807.492512986638, 7828.256315650005, 6974.6861391238535, 7133.714651413885, 7164.0, 7238.0952380952385, 7288.0, 7025.402840758593, 7081.82295442618, 7088.503804733826, 7163.534370265916, 7229.963850180716, 6434.549581529248, 6806.0, 6813.0, 6813.1142951415995, 6816.863662726619, 5140.614453915897, 5156.793728250828, 5167.302414174189, 5167.534921857051, 5179.507946745053, 5183.455737147545, 5222.686638801684, 5273.841784746463, 5275.1032324505695, 5349.144136938071], "value": 4429.457242220363, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [315.41647951290116, 329.08022078291174, 332.4896284203748, 333.9181900434394, 335.5704697986578, 5177.792888284464, 5180.0, 5231.61362239006, 5242.18746094356, 5251.264822924789, 5005.724685142316, 5081.542661160494, 5149.0, 5193.246979188016, 5289.814856480024, 4129.6283334499885, 4161.875143745692, 4176.248275310443, 4183.497980242375, 4197.202531519017, 4146.647534959517, 4151.916961660765, 4169.457970463835, 4174.8121334539865, 4184.309588917825, 3963.623455771697, 3965.940510892339, 4109.835606575748, 4129.525104612978, 4132.933533233383, 4085.7140000199997, 4090.5091389033505, 4098.180363927211, 4101.630853223194, 4118.114605359832, 3645.2891686121216, 3648.3068217038945, 3657.7988210648405, 3672.889813305605, 3699.5375578052744, 4888.193448081062, 4930.211166213425, 5038.118329292396, 5052.646314757969, 5070.188769796815, 4653.115907977473, 4671.089137618166, 4692.906141877177, 4715.3869996900185, 4727.149113159629, 5469.0, 5473.75368108436, 5484.588655850827, 5505.366882808488, 5508.228847961289, 3955.169414422946, 3972.3397219164867, 4037.425404092406, 4045.898852528681, 4052.8581499647667, 5655.632383895034, 5658.773649054074, 5680.147977803321, 5701.173329867229, 5725.656460612377, 4085.305498065336, 4101.671866250693, 4102.630763231324, 4116.670666346685, 4124.237016152021, 3756.549214094326, 3982.900427489336, 4165.83336666536, 4174.770387628649, 4180.728252663567, 4564.0, 4568.908621827612, 4595.195840727859, 4609.700369475973, 4610.677252592286, 5521.585881058937, 5524.14375771756, 5534.1975413564805, 5543.47554422537, 5544.63959842609, 1369.760291948905, 1373.7046534995025, 1458.3364569121024, 1475.970480590382, 1476.6234610174338, 966.840471322238, 968.6803354892868, 982.1798798003604, 984.6799790068256, 987.3039507147444, 5530.7511161997, 5537.889242215133, 5545.556355491551, 5554.361248456398, 5591.720413979285, 5948.67282299469, 5955.434233747787, 5963.016102343065, 5994.7736845523805, 6033.607815491979, 5427.213054107132, 5599.384067752544, 5604.887902242014, 5614.101743720985, 5626.465485778845, 3198.1365031441846, 3198.920026999297, 3209.1335339458215, 3213.775035747475, 3225.59680039995, 5650.406707295757, 6207.099970504251, 6232.345603711545, 6234.594751341148, 6257.405546473078, 5742.454466825645, 5748.770049197985, 5753.683547404933, 5776.1624564437925, 5802.88394232113, 5851.215379309267, 5883.61756485827, 5903.793367232126, 5910.527157827363, 5921.378255283219, 5602.467765562265, 5670.92252472029, 5690.146478028329, 5724.0, 5726.2269593606, 5645.68948707825, 5665.405132461032, 5666.886662266649, 5675.985025315953, 5692.1461780733225, 5639.238702775073, 5919.378465261086, 5920.822375328747, 5926.585139040399, 5958.672273025025, 3979.721419500607, 4030.7783071930153, 4033.5764744701382, 4042.8584999524874, 4073.694472914484, 5337.706426146599, 5669.461401166883, 5688.516476099578, 5712.257406537124, 5717.771289148387, 5422.430644782399, 5566.749496272759, 5596.86007849807, 5610.13042978339, 5633.0, 5593.196626293997, 5594.797118619354, 5604.327480702179, 5627.396192085236, 5649.13578519096, 5209.0, 5510.760078982197, 5514.80698175562, 5519.586031047767, 5537.529310008695, 5536.390996990408, 5538.833834984957, 5563.527100196528, 5582.804601838916, 5585.134304182858, 5440.700761458158, 5441.972790136024, 5454.972490267781, 5456.972715136399, 5470.425605311385], "value": 4257.502748937564, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [778.9221077892211, 827.8592639251327, 839.2782207301723, 846.018618402653, 848.1730312944879, 12668.303243321614, 12969.610911672642, 13129.540466083688, 13159.802602960963, 13174.27541485218, 13156.868431315685, 13482.191068535894, 13673.24797136157, 13718.0, 13729.107608005472, 677.695037233247, 787.9842403151933, 801.1907972947307, 810.8378324335127, 811.9472234304781, 797.9202079792033, 807.1605530248564, 808.107041718898, 808.458332916947, 808.514891065362, 843.5992903370911, 853.3770347646222, 853.4921721575653, 854.6325080215505, 856.1438561438562, 692.6709812838911, 825.2078005115037, 846.8856704344896, 852.3479538153317, 855.5080828523562, 617.6417677746896, 617.660286842235, 619.9690015499252, 620.2742790934617, 620.4663988969482, 12044.518219271218, 12050.216735912225, 12066.939665301705, 12110.636680899586, 12116.818247726203, 13090.41093150801, 13523.188608683511, 14290.64273393163, 14341.856581434156, 14450.421983120663, 10495.160387169008, 10578.894211057868, 10710.250282480229, 11474.483648235766, 11496.540138394454, 4298.161858437606, 4338.370936214263, 4470.262406702923, 4478.798454069574, 4532.206863798855, 50387.74806125946, 50473.74763126198, 51229.7438512805, 51361.0, 51508.484915150744, 10317.535710893027, 10664.146868250522, 10856.565737370574, 10969.725756856142, 11210.775784484427, 18788.530286742942, 18815.0, 18920.24319027251, 18923.716144257847, 18931.716024259647, 8128.9593552031865, 8144.674213031531, 8385.706500272461, 8388.454750441202, 8549.444286121507, 4689.929651055237, 4759.619230461555, 4801.783919723621, 4826.710397376099, 4846.07924494352, 3316.121227874615, 3382.1713680148027, 3392.6776956189124, 3395.966040339614, 3398.7620866539105, 513.907496650599, 603.2640178981635, 609.8841220168242, 610.0665981049002, 610.2524407600743, 688.2051230828401, 778.8169780101784, 780.6994307191773, 780.7423550228411, 800.507687772016, 9632.133108020313, 9672.177864881563, 9728.0, 9851.0, 9905.108540231413, 701.551007355293, 723.1250187273458, 726.5676922231264, 730.8026832755127, 737.3695490355776, 4450.777461126931, 4562.452505699337, 4589.908201835945, 4608.331791890158, 4613.354130421743, 71919.0, 73116.63441682865, 73293.26706732971, 73812.63093684497, 73926.0, 61838.69080654568, 63612.68193659095, 64171.0, 65561.0, 65972.67013664998, 55046.44953550494, 56600.99999999918, 57372.139417908766, 57568.71215643979, 58317.0, 8806.075362087146, 8965.0, 9044.864327035231, 9048.366614337196, 9093.318001149808, 36402.45396319004, 36953.0, 37028.62971370252, 37379.25241495209, 37519.624803752704, 47254.76372618115, 50337.244941326644, 50843.491565083874, 51157.7442112787, 52593.21110183274, 11493.310401375944, 11678.0, 11695.12620102581, 11823.290602563873, 11962.820557691468, 12769.233845969271, 12785.74428511406, 12821.551245706356, 12927.676808079874, 13108.934455327662, 12165.574204902787, 12378.257304561756, 12463.127581069239, 12831.679208019874, 12944.805827912778, 12171.0, 12260.693482663004, 12302.81545776832, 12358.002755095944, 12423.751524969632, 12202.877971220174, 12311.19977201479, 12375.319357435428, 12630.936845316082, 12895.355232238539, 10224.386536807815, 10265.58937642486, 10449.0, 10644.254902156774, 10710.196735245043, 9663.9033609663, 9897.752556186153, 9935.65225217114, 9950.55222514974, 10018.0], "value": 6792.120818836892, "unit": "score"}], "name": "dromaeo_css", "value": 6952.478039012108, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 107.0, 110.0, 110.0, 113.0, 104.0, 112.0, 111.0, 109.0], "value": 109.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 127.0, 159.0, 178.0, 134.0, 152.0, 157.0, 136.0, 184.0, 130.0], "value": 151.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 161.0, 158.0, 158.0, 159.0, 159.0, 160.0, 160.0, 162.0, 160.0], "value": 159.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 117.0, 106.0, 74.0, 72.0, 76.0, 116.0, 107.0, 76.0], "value": 89.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 93.0, 150.0, 92.0, 93.0, 92.0, 149.0, 92.0, 92.0, 141.0], "value": 108.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 117.0, 117.0, 117.0, 116.0, 117.0, 116.0, 117.0], "value": 116.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 145.0, 146.0, 146.0, 145.0, 145.0, 145.0], "value": 145.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 95.0, 95.0, 95.0, 94.0, 95.0, 95.0, 95.0], "value": 94.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 58.0, 57.0, 58.0, 57.0, 57.0, 74.0, 56.0, 58.0], "value": 59.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 62.0, 62.0, 62.0, 63.0, 62.0, 63.0, 63.0, 62.0, 62.0], "value": 62.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 77.0, 78.0, 78.0, 78.0, 78.0, 78.0, 78.0, 79.0], "value": 78.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 127.0, 126.0, 128.0, 131.0, 135.0, 134.0, 170.0, 128.0], "value": 133.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 205.0, 206.0, 201.0, 202.0, 205.0, 199.0, 205.0, 200.0, 204.0], "value": 202.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 72.0, 70.0, 70.0, 69.0, 68.0, 69.0, 71.0, 68.0, 67.0], "value": 69.5, "unit": "score"}], "name": "kraken", "value": 1581.3000000000002, "alertThreshold": 2.0}]} 22:31:22 INFO - Return code: 0 22:31:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:31:22 ERROR - # TBPL SUCCESS # 22:31:22 INFO - Running post-action listener: _resource_record_post_action 22:31:22 INFO - Running post-run listener: _resource_record_post_run 22:31:23 INFO - Total resource usage - Wall time: 1195s; CPU: 13.0%; Read bytes: 16384; Write bytes: 536346624; Read time: 300; Write time: 963972 22:31:23 INFO - install - Wall time: 17s; CPU: 16.0%; Read bytes: 0; Write bytes: 203030528; Read time: 0; Write time: 599268 22:31:23 INFO - run-tests - Wall time: 1178s; CPU: 13.0%; Read bytes: 16384; Write bytes: 284979200; Read time: 300; Write time: 352724 22:31:23 INFO - Running post-run listener: _upload_blobber_files 22:31:23 INFO - Blob upload gear active. 22:31:23 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:31:23 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 22:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Inbound', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Inbound -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:31:24 INFO - (blobuploader) - INFO - Open directory for files ... 22:31:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:31:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:31:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:24 INFO - (blobuploader) - INFO - Done attempting. 22:31:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:31:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:31:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:25 INFO - (blobuploader) - INFO - Done attempting. 22:31:25 INFO - (blobuploader) - INFO - Iteration through files over. 22:31:25 INFO - Return code: 0 22:31:25 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:31:25 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:31:25 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc98b331c76fdd48c20a712d2e8cfb051aebe8efd0f2ff4b8776304d9dd585b7cdd3dbc359371faf00b0882efdbab3bf674815b621af0e7787f5d35eb3b115bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36d90ee11115052b5673b1c39ac1841b7078ca282a177cd5674f904429bc649c7be98ed5dc6eae71528c6fee05dd879270f595e153f39b3853d9c66b1761bb9d"} 22:31:25 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:31:25 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:31:25 INFO - Contents: 22:31:25 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc98b331c76fdd48c20a712d2e8cfb051aebe8efd0f2ff4b8776304d9dd585b7cdd3dbc359371faf00b0882efdbab3bf674815b621af0e7787f5d35eb3b115bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36d90ee11115052b5673b1c39ac1841b7078ca282a177cd5674f904429bc649c7be98ed5dc6eae71528c6fee05dd879270f595e153f39b3853d9c66b1761bb9d"} 22:31:25 INFO - Running post-run listener: copy_logs_to_upload_dir 22:31:25 INFO - Copying logs to upload dir... 22:31:25 INFO - mkdir: /builds/slave/test/build/upload/logs 22:31:25 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.054458 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2016-04-25 22:31:25.665229) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 22:31:25.669103) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc98b331c76fdd48c20a712d2e8cfb051aebe8efd0f2ff4b8776304d9dd585b7cdd3dbc359371faf00b0882efdbab3bf674815b621af0e7787f5d35eb3b115bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36d90ee11115052b5673b1c39ac1841b7078ca282a177cd5674f904429bc649c7be98ed5dc6eae71528c6fee05dd879270f595e153f39b3853d9c66b1761bb9d"} build_url:https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011459 build_url: 'https://queue.taskcluster.net/v1/task/eFIIghWcT0mzL-Z3PwOUhA/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/fc98b331c76fdd48c20a712d2e8cfb051aebe8efd0f2ff4b8776304d9dd585b7cdd3dbc359371faf00b0882efdbab3bf674815b621af0e7787f5d35eb3b115bd", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/36d90ee11115052b5673b1c39ac1841b7078ca282a177cd5674f904429bc649c7be98ed5dc6eae71528c6fee05dd879270f595e153f39b3853d9c66b1761bb9d"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-25 22:31:25.693575) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:31:25.693907) ========= 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=5cf47e208febbcd81da23b2e0000026f-1461643029.629389-2111309086 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004477 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-25 22:31:25.777435) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 22:31:25.777758) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-25 22:31:25.778054) ========= ========= Total master_lag: 0.17 =========