builder: mozilla-inbound_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-003 starttime: 1462586525.14 results: success (0) buildid: 20160506163016 builduid: 0d27cba34b644d8d8ade2eff18d375c2 revision: d50ac88f8feef390e875b3f92f6a69544107d6c1 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.142764) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.143274) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.143573) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.184400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.184697) ========= 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=7de08804244eb36560b44971000001c3-1462581191.140079-614059795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-05-06 19:02:05-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 111M=0s 2016-05-06 19:02:05 (111 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.095605 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.300060) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.300345) ========= 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=7de08804244eb36560b44971000001c3-1462581191.140079-614059795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.022100 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.365931) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.366266) ========= bash -c 'python archiver_client.py mozharness --repo integration/mozilla-inbound --rev d50ac88f8feef390e875b3f92f6a69544107d6c1 --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 d50ac88f8feef390e875b3f92f6a69544107d6c1 --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=7de08804244eb36560b44971000001c3-1462581191.140079-614059795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-05-06 19:02:05,801 truncating revision to first 12 chars 2016-05-06 19:02:05,801 Setting DEBUG logging. 2016-05-06 19:02:05,801 attempt 1/10 2016-05-06 19:02:05,802 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/mozilla-inbound/d50ac88f8fee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 19:02:06,083 unpacking tar archive at: mozilla-inbound-d50ac88f8fee/testing/mozharness/ program finished with exit code 0 elapsedTime=0.575558 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.955067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.955353) ========= script_repo_revision: d50ac88f8feef390e875b3f92f6a69544107d6c1 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.955740) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.956003) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 19:02:05.966552) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-05-06 19:02:05.966815) ========= /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=7de08804244eb36560b44971000001c3-1462581191.140079-614059795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 19:02:06 INFO - MultiFileLogger online at 20160506 19:02:06 in /builds/slave/test 19:02:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Inbound --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Inbound 19:02:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:02:06 INFO - {'append_to_log': False, 19:02:06 INFO - 'base_work_dir': '/builds/slave/test', 19:02:06 INFO - 'blob_upload_branch': 'Mozilla-Inbound', 19:02:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:02:06 INFO - 'branch': 'Mozilla-Inbound', 19:02:06 INFO - 'buildbot_json_path': 'buildprops.json', 19:02:06 INFO - 'config_files': ('talos/linux_config.py',), 19:02:06 INFO - 'default_actions': ('clobber', 19:02:06 INFO - 'read-buildbot-config', 19:02:06 INFO - 'download-and-extract', 19:02:06 INFO - 'populate-webroot', 19:02:06 INFO - 'create-virtualenv', 19:02:06 INFO - 'install', 19:02:06 INFO - 'run-tests'), 19:02:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:02:06 INFO - 'download_minidump_stackwalk': True, 19:02:06 INFO - 'download_symbols': 'ondemand', 19:02:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:02:06 INFO - 'tooltool.py': '/tools/tooltool.py', 19:02:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:02:06 INFO - '/tools/misc-python/virtualenv.py')}, 19:02:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:02:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:02:06 INFO - 'installer_path': 'installer.exe', 19:02:06 INFO - 'log_level': 'info', 19:02:06 INFO - 'log_name': 'talos', 19:02:06 INFO - 'log_to_console': True, 19:02:06 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 19:02:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 19:02:06 INFO - 'opt_config_files': (), 19:02:06 INFO - 'pip_index': False, 19:02:06 INFO - 'sps_profile': False, 19:02:06 INFO - 'sps_profile_interval': 0, 19:02:06 INFO - 'suite': 'dromaeojs', 19:02:06 INFO - 'system_bits': '32', 19:02:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:02:06 INFO - 'title': 'talos-linux64-ix-003', 19:02:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:02:06 INFO - 'use_talos_json': True, 19:02:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:02:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:02:06 INFO - 'work_dir': 'build'} 19:02:06 INFO - ##### 19:02:06 INFO - ##### Running clobber step. 19:02:06 INFO - ##### 19:02:06 INFO - Running pre-action listener: _resource_record_pre_action 19:02:06 INFO - Running main action method: clobber 19:02:06 INFO - rmtree: /builds/slave/test/build 19:02:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:02:06 INFO - Running post-action listener: _resource_record_post_action 19:02:06 INFO - ##### 19:02:06 INFO - ##### Finished clobber step (success) 19:02:06 INFO - ##### 19:02:06 INFO - ##### 19:02:06 INFO - ##### Running read-buildbot-config step. 19:02:06 INFO - ##### 19:02:06 INFO - Running pre-action listener: _resource_record_pre_action 19:02:06 INFO - Running main action method: read_buildbot_config 19:02:06 INFO - Using buildbot properties: 19:02:06 INFO - { 19:02:06 INFO - "project": "", 19:02:06 INFO - "product": "firefox", 19:02:06 INFO - "script_repo_revision": "production", 19:02:06 INFO - "builddir": "mozilla-inbound_ubuntu64_hw_test-dromaeojs", 19:02:06 INFO - "repository": "", 19:02:06 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-inbound pgo talos dromaeojs", 19:02:06 INFO - "buildid": "20160506163016", 19:02:06 INFO - "pgo_build": "True", 19:02:06 INFO - "basedir": "/builds/slave/test", 19:02:06 INFO - "buildnumber": 891, 19:02:06 INFO - "slavename": "talos-linux64-ix-003", 19:02:06 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 19:02:06 INFO - "platform": "ubuntu64_hw", 19:02:06 INFO - "scheduler": "tests-mozilla-inbound-linux64-pgo-talos", 19:02:06 INFO - "branch": "mozilla-inbound", 19:02:06 INFO - "repo_path": "integration/mozilla-inbound", 19:02:06 INFO - "revision": "d50ac88f8feef390e875b3f92f6a69544107d6c1", 19:02:06 INFO - "stage_platform": "linux64-pgo", 19:02:06 INFO - "builduid": "0d27cba34b644d8d8ade2eff18d375c2", 19:02:06 INFO - "slavebuilddir": "test" 19:02:06 INFO - } 19:02:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2. 19:02:06 INFO - Running post-action listener: _resource_record_post_action 19:02:06 INFO - ##### 19:02:06 INFO - ##### Finished read-buildbot-config step (success) 19:02:06 INFO - ##### 19:02:06 INFO - ##### 19:02:06 INFO - ##### Running download-and-extract step. 19:02:06 INFO - ##### 19:02:06 INFO - Running pre-action listener: _resource_record_pre_action 19:02:06 INFO - Running main action method: download_and_extract 19:02:06 INFO - mkdir: /builds/slave/test/build/tests 19:02:06 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')]} 19:02:06 INFO - https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 19:02:06 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:02:06 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:02:06 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:07 INFO - Downloaded 1361 bytes. 19:02:07 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.linux-x86_64.test_packages.json 19:02:07 INFO - Using the following test package requirements: 19:02:07 INFO - {u'common': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:02:07 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 19:02:07 INFO - u'gtest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.gtest.tests.zip'], 19:02:07 INFO - u'jittest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'jsshell-linux-x86_64.zip'], 19:02:07 INFO - u'mochitest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 19:02:07 INFO - u'mozbase': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'], 19:02:07 INFO - u'reftest': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.reftest.tests.zip'], 19:02:07 INFO - u'talos': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip'], 19:02:07 INFO - u'web-platform': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 19:02:07 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip', 19:02:07 INFO - u'firefox-49.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 19:02:07 INFO - Downloading packages: [u'firefox-49.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 19:02:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:07 INFO - https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:02:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:02:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:02:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:08 INFO - Downloaded 23894346 bytes. 19:02:08 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'] 19:02:08 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 19:02:09 INFO - Return code: 0 19:02:09 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 19:02:09 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')]} 19:02:09 INFO - https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 19:02:09 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:02:09 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.common.tests.zip 19:02:09 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:11 INFO - Downloaded 23894346 bytes. 19:02:11 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'] 19:02:11 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 19:02:12 INFO - Return code: 0 19:02:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:12 INFO - https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 19:02:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:02:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.talos.tests.zip 19:02:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:13 INFO - Downloaded 10840152 bytes. 19:02:13 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'] 19:02:13 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 19:02:13 INFO - Return code: 0 19:02:13 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')]} 19:02:13 INFO - https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 19:02:13 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:02:13 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:02:13 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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 19:02:15 INFO - Downloaded 58408871 bytes. 19:02:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:02:15 INFO - mkdir: /builds/slave/test/properties 19:02:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:02:15 INFO - Writing to file /builds/slave/test/properties/build_url 19:02:15 INFO - Contents: 19:02:15 INFO - build_url:https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 19:02:16 INFO - Running post-action listener: _resource_record_post_action 19:02:16 INFO - Running post-action listener: set_extra_try_arguments 19:02:16 INFO - ##### 19:02:16 INFO - ##### Finished download-and-extract step (success) 19:02:16 INFO - ##### 19:02:16 INFO - ##### 19:02:16 INFO - ##### Running populate-webroot step. 19:02:16 INFO - ##### 19:02:16 INFO - Running pre-action listener: _resource_record_pre_action 19:02:16 INFO - Running main action method: populate_webroot 19:02:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:02:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:02:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:02:16 INFO - '--tppagecycles', 19:02:16 INFO - '7'], 19:02:16 INFO - 'tests': ['tsvgm']}}, 19:02:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:02:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:02:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:02:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:02:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:02:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['damp', 'tps']}, 19:02:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'tests': ['damp', 'tps']}, 19:02:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['dromaeo_dom']}, 19:02:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:02:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['a11yr', 19:02:16 INFO - 'ts_paint', 19:02:16 INFO - 'tpaint', 19:02:16 INFO - 'sessionrestore', 19:02:16 INFO - 'sessionrestore_no_auto_restore', 19:02:16 INFO - 'tabpaint']}, 19:02:16 INFO - 'other-e10s': {'tests': ['a11yr', 19:02:16 INFO - 'ts_paint', 19:02:16 INFO - 'tpaint', 19:02:16 INFO - 'sessionrestore', 19:02:16 INFO - 'sessionrestore_no_auto_restore', 19:02:16 INFO - 'tabpaint']}, 19:02:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:02:16 INFO - 'ts_paint', 19:02:16 INFO - 'tpaint', 19:02:16 INFO - 'sessionrestore', 19:02:16 INFO - 'sessionrestore_no_auto_restore', 19:02:16 INFO - 'tabpaint']}, 19:02:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:02:16 INFO - 'ts_paint', 19:02:16 INFO - 'tpaint', 19:02:16 INFO - 'sessionrestore', 19:02:16 INFO - 'sessionrestore_no_auto_restore', 19:02:16 INFO - 'tabpaint']}, 19:02:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['a11yr', 19:02:16 INFO - 'ts_paint', 19:02:16 INFO - 'tpaint', 19:02:16 INFO - 'sessionrestore', 19:02:16 INFO - 'sessionrestore_no_auto_restore', 19:02:16 INFO - 'tabpaint']}, 19:02:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['a11yr', 19:02:16 INFO - 'ts_paint', 19:02:16 INFO - 'tpaint', 19:02:16 INFO - 'sessionrestore', 19:02:16 INFO - 'sessionrestore_no_auto_restore', 19:02:16 INFO - 'tabpaint']}, 19:02:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['tsvgx', 19:02:16 INFO - 'tsvgr_opacity', 19:02:16 INFO - 'tart', 19:02:16 INFO - 'tscrollx', 19:02:16 INFO - 'cart']}, 19:02:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:02:16 INFO - 'tsvgr_opacity', 19:02:16 INFO - 'tart', 19:02:16 INFO - 'tscrollx', 19:02:16 INFO - 'cart']}, 19:02:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'talos_options': ['--disable-e10s'], 19:02:16 INFO - 'tests': ['tp5o']}, 19:02:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'tests': ['tp5o']}, 19:02:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'talos_options': ['--disable-e10s', 19:02:16 INFO - '--xperf_path', 19:02:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:16 INFO - 'tests': ['tp5n']}, 19:02:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:02:16 INFO - 'talos_options': ['--xperf_path', 19:02:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:02:16 INFO - 'tests': ['tp5n']}}, 19:02:16 INFO - 'talos.zip': {'path': '', 19:02:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:02:16 INFO - Running post-action listener: _resource_record_post_action 19:02:16 INFO - ##### 19:02:16 INFO - ##### Finished populate-webroot step (success) 19:02:16 INFO - ##### 19:02:16 INFO - ##### 19:02:16 INFO - ##### Running create-virtualenv step. 19:02:16 INFO - ##### 19:02:16 INFO - Running pre-action listener: _resource_record_pre_action 19:02:16 INFO - Running main action method: create_virtualenv 19:02:16 INFO - Creating virtualenv /builds/slave/test/build/venv 19:02:16 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 19:02:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:02:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:02:16 INFO - Using real prefix '/usr' 19:02:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:02:16 INFO - Installing distribute.............................................................................................................................................................................................done. 19:02:18 INFO - Installing pip.................done. 19:02:18 INFO - Return code: 0 19:02:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:02:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:18 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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:18 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 19:02:18 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 19:02:18 INFO - Using env: {'DISPLAY': ':0', 19:02:18 INFO - 'HOME': '/home/cltbld', 19:02:18 INFO - 'LANG': 'en_US.UTF-8', 19:02:18 INFO - 'LANGUAGE': 'en_US:en', 19:02:18 INFO - 'LOGNAME': 'cltbld', 19:02:18 INFO - 'MAIL': '/var/mail/cltbld', 19:02:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:18 INFO - 'MOZ_NO_REMOTE': '1', 19:02:18 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:18 INFO - 'NO_EM_RESTART': '1', 19:02:18 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:18 INFO - 'PWD': '/builds/slave/test', 19:02:18 INFO - 'SHELL': '/bin/bash', 19:02:18 INFO - 'SHLVL': '1', 19:02:18 INFO - 'TERM': 'linux', 19:02:18 INFO - 'TMOUT': '86400', 19:02:18 INFO - 'USER': 'cltbld', 19:02:18 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:18 INFO - '_': '/tools/buildbot/bin/python'} 19:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:19 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 19:02:19 INFO - Cleaning up... 19:02:19 INFO - Return code: 0 19:02:19 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 19:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:19 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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:19 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 19:02:19 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 19:02:19 INFO - Using env: {'DISPLAY': ':0', 19:02:19 INFO - 'HOME': '/home/cltbld', 19:02:19 INFO - 'LANG': 'en_US.UTF-8', 19:02:19 INFO - 'LANGUAGE': 'en_US:en', 19:02:19 INFO - 'LOGNAME': 'cltbld', 19:02:19 INFO - 'MAIL': '/var/mail/cltbld', 19:02:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:19 INFO - 'MOZ_NO_REMOTE': '1', 19:02:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:19 INFO - 'NO_EM_RESTART': '1', 19:02:19 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:19 INFO - 'PWD': '/builds/slave/test', 19:02:19 INFO - 'SHELL': '/bin/bash', 19:02:19 INFO - 'SHLVL': '1', 19:02:19 INFO - 'TERM': 'linux', 19:02:19 INFO - 'TMOUT': '86400', 19:02:19 INFO - 'USER': 'cltbld', 19:02:19 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:19 INFO - '_': '/tools/buildbot/bin/python'} 19:02:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:19 INFO - Downloading/unpacking psutil>=0.7.1 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:02:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:21 INFO - Installing collected packages: psutil 19:02:21 INFO - Running setup.py install for psutil 19:02:21 INFO - building 'psutil._psutil_linux' extension 19:02:21 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 19:02:21 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 19:02:21 INFO - building 'psutil._psutil_posix' extension 19:02:21 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 19:02:21 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 19:02:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:02:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:02:21 INFO - Successfully installed psutil 19:02:21 INFO - Cleaning up... 19:02:21 INFO - Return code: 0 19:02:21 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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', '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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02: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', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 19:02: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 mozsystemmonitor==0.0.0 19:02:21 INFO - Using env: {'DISPLAY': ':0', 19:02:21 INFO - 'HOME': '/home/cltbld', 19:02:21 INFO - 'LANG': 'en_US.UTF-8', 19:02:21 INFO - 'LANGUAGE': 'en_US:en', 19:02:21 INFO - 'LOGNAME': 'cltbld', 19:02:21 INFO - 'MAIL': '/var/mail/cltbld', 19:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:21 INFO - 'MOZ_NO_REMOTE': '1', 19:02:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:21 INFO - 'NO_EM_RESTART': '1', 19:02:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:21 INFO - 'PWD': '/builds/slave/test', 19:02:21 INFO - 'SHELL': '/bin/bash', 19:02:21 INFO - 'SHLVL': '1', 19:02:21 INFO - 'TERM': 'linux', 19:02:21 INFO - 'TMOUT': '86400', 19:02:21 INFO - 'USER': 'cltbld', 19:02:21 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:21 INFO - '_': '/tools/buildbot/bin/python'} 19:02:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:22 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 19:02:22 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 19:02:22 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 19:02:22 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 19:02:22 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 19:02:22 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 19:02:22 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 19:02:23 INFO - Downloading mozsystemmonitor-0.0.tar.gz 19:02:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 19:02:23 INFO - Installing collected packages: mozsystemmonitor 19:02:23 INFO - Running setup.py install for mozsystemmonitor 19:02:24 INFO - Successfully installed mozsystemmonitor 19:02:24 INFO - Cleaning up... 19:02:24 INFO - Return code: 0 19:02:24 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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', '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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:02: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 blobuploader==1.2.4 19:02:24 INFO - Using env: {'DISPLAY': ':0', 19:02:24 INFO - 'HOME': '/home/cltbld', 19:02:24 INFO - 'LANG': 'en_US.UTF-8', 19:02:24 INFO - 'LANGUAGE': 'en_US:en', 19:02:24 INFO - 'LOGNAME': 'cltbld', 19:02:24 INFO - 'MAIL': '/var/mail/cltbld', 19:02:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:24 INFO - 'MOZ_NO_REMOTE': '1', 19:02:24 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:24 INFO - 'NO_EM_RESTART': '1', 19:02:24 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:24 INFO - 'PWD': '/builds/slave/test', 19:02:24 INFO - 'SHELL': '/bin/bash', 19:02:24 INFO - 'SHLVL': '1', 19:02:24 INFO - 'TERM': 'linux', 19:02:24 INFO - 'TMOUT': '86400', 19:02:24 INFO - 'USER': 'cltbld', 19:02:24 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:24 INFO - '_': '/tools/buildbot/bin/python'} 19:02:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:24 INFO - Downloading/unpacking blobuploader==1.2.4 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:26 INFO - Downloading blobuploader-1.2.4.tar.gz 19:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:02:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:02:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02: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 19:02:27 INFO - Downloading docopt-0.6.1.tar.gz 19:02:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:02:27 INFO - Installing collected packages: blobuploader, requests, docopt 19:02:27 INFO - Running setup.py install for blobuploader 19:02:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:02:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:02:27 INFO - Running setup.py install for requests 19:02:27 INFO - Running setup.py install for docopt 19:02:27 INFO - Successfully installed blobuploader requests docopt 19:02:27 INFO - Cleaning up... 19:02:27 INFO - Return code: 0 19:02:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:27 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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:27 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 19:02:27 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 19:02:27 INFO - Using env: {'DISPLAY': ':0', 19:02:27 INFO - 'HOME': '/home/cltbld', 19:02:27 INFO - 'LANG': 'en_US.UTF-8', 19:02:27 INFO - 'LANGUAGE': 'en_US:en', 19:02:27 INFO - 'LOGNAME': 'cltbld', 19:02:27 INFO - 'MAIL': '/var/mail/cltbld', 19:02:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:27 INFO - 'MOZ_NO_REMOTE': '1', 19:02:27 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:27 INFO - 'NO_EM_RESTART': '1', 19:02:27 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:27 INFO - 'PWD': '/builds/slave/test', 19:02:27 INFO - 'SHELL': '/bin/bash', 19:02:27 INFO - 'SHLVL': '1', 19:02:27 INFO - 'TERM': 'linux', 19:02:27 INFO - 'TMOUT': '86400', 19:02:27 INFO - 'USER': 'cltbld', 19:02:27 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:27 INFO - '_': '/tools/buildbot/bin/python'} 19:02:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:27 INFO - Running setup.py (path:/tmp/pip-5IWNVr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:28 INFO - Running setup.py (path:/tmp/pip-Ej31BW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:28 INFO - Running setup.py (path:/tmp/pip-t1K3Zc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:28 INFO - Running setup.py (path:/tmp/pip-7hyabH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:28 INFO - Running setup.py (path:/tmp/pip-wm0u2b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:28 INFO - Running setup.py (path:/tmp/pip-xrk5Zv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:28 INFO - Running setup.py (path:/tmp/pip-g4YfYD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:28 INFO - Running setup.py (path:/tmp/pip-niNPNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:28 INFO - Running setup.py (path:/tmp/pip-zWH8eb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:28 INFO - Running setup.py (path:/tmp/pip-yNS0LW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:28 INFO - Running setup.py (path:/tmp/pip-5I4Q08-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:28 INFO - Running setup.py (path:/tmp/pip-h7QcQZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:28 INFO - Running setup.py (path:/tmp/pip-YOOfcr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:28 INFO - Running setup.py (path:/tmp/pip-dEzZCd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:28 INFO - Running setup.py (path:/tmp/pip-NeHVQI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:29 INFO - Running setup.py (path:/tmp/pip-3NIMsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:29 INFO - Running setup.py (path:/tmp/pip-NnlMvm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:02:29 INFO - Running setup.py install for manifestparser 19:02:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Running setup.py install for mozcrash 19:02:29 INFO - Running setup.py install for mozdebug 19:02:29 INFO - Running setup.py install for mozdevice 19:02:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Running setup.py install for mozfile 19:02:29 INFO - Running setup.py install for mozhttpd 19:02:29 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Running setup.py install for mozinfo 19:02:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Running setup.py install for mozInstall 19:02:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:02:29 INFO - Running setup.py install for mozleak 19:02:30 INFO - Running setup.py install for mozlog 19:02:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Running setup.py install for moznetwork 19:02:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Running setup.py install for mozprocess 19:02:30 INFO - Running setup.py install for mozprofile 19:02:30 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Running setup.py install for mozrunner 19:02:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Running setup.py install for mozscreenshot 19:02:30 INFO - Running setup.py install for moztest 19:02:30 INFO - Running setup.py install for mozversion 19:02:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:02:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:02:30 INFO - Cleaning up... 19:02:30 INFO - Return code: 0 19:02:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02: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')]} 19:02:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02: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')]} 19:02:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:30 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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:30 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 19:02:30 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 19:02:30 INFO - Using env: {'DISPLAY': ':0', 19:02:30 INFO - 'HOME': '/home/cltbld', 19:02:30 INFO - 'LANG': 'en_US.UTF-8', 19:02:30 INFO - 'LANGUAGE': 'en_US:en', 19:02:30 INFO - 'LOGNAME': 'cltbld', 19:02:30 INFO - 'MAIL': '/var/mail/cltbld', 19:02:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:30 INFO - 'MOZ_NO_REMOTE': '1', 19:02:30 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:30 INFO - 'NO_EM_RESTART': '1', 19:02:30 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:30 INFO - 'PWD': '/builds/slave/test', 19:02:30 INFO - 'SHELL': '/bin/bash', 19:02:30 INFO - 'SHLVL': '1', 19:02:30 INFO - 'TERM': 'linux', 19:02:30 INFO - 'TMOUT': '86400', 19:02:30 INFO - 'USER': 'cltbld', 19:02:30 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:30 INFO - '_': '/tools/buildbot/bin/python'} 19:02:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:02:31 INFO - Running setup.py (path:/tmp/pip-Y2Ey3Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:02:31 INFO - Running setup.py (path:/tmp/pip-5Js5HE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:02:31 INFO - Running setup.py (path:/tmp/pip-oSnTVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:02:31 INFO - Running setup.py (path:/tmp/pip-L0Cco6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:02:31 INFO - Running setup.py (path:/tmp/pip-qeNFGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:02:31 INFO - Running setup.py (path:/tmp/pip-hBUaq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:02:31 INFO - Running setup.py (path:/tmp/pip-60SvwN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:02:31 INFO - Running setup.py (path:/tmp/pip-kgSo9I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:02:31 INFO - Running setup.py (path:/tmp/pip-wizaOD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:02:31 INFO - Running setup.py (path:/tmp/pip-E6rJnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:02:31 INFO - Running setup.py (path:/tmp/pip-sziCmE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:02:31 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)) 19:02:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:02:31 INFO - Running setup.py (path:/tmp/pip-B7nyad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:02:32 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)) 19:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:02:32 INFO - Running setup.py (path:/tmp/pip-eb5C5L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:02:32 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)) 19:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:02:32 INFO - Running setup.py (path:/tmp/pip-wAYjy7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:02:32 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)) 19:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:32 INFO - Running setup.py (path:/tmp/pip-eyheMG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:02:32 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)) 19:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:02:32 INFO - Running setup.py (path:/tmp/pip-DetOuD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:02:32 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)) 19:02:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:02:32 INFO - Running setup.py (path:/tmp/pip-spi_fa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:02:32 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)) 19:02:32 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)) 19:02:32 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)) 19:02:32 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)) 19:02:32 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)) 19:02:32 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)) 19:02:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:34 INFO - Downloading blessings-1.6.tar.gz 19:02:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:02:34 INFO - Installing collected packages: blessings 19:02:34 INFO - Running setup.py install for blessings 19:02:34 INFO - Successfully installed blessings 19:02:34 INFO - Cleaning up... 19:02:34 INFO - Return code: 0 19:02:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:02:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:34 INFO - Reading from file tmpfile_stdout 19:02:34 INFO - Current package versions: 19:02:34 INFO - argparse == 1.2.1 19:02:34 INFO - blessings == 1.6 19:02:34 INFO - blobuploader == 1.2.4 19:02:34 INFO - docopt == 0.6.1 19:02:34 INFO - manifestparser == 1.1 19:02:34 INFO - mozInstall == 1.12 19:02:34 INFO - mozcrash == 0.17 19:02:34 INFO - mozdebug == 0.1 19:02:34 INFO - mozdevice == 0.48 19:02:34 INFO - mozfile == 1.2 19:02:34 INFO - mozhttpd == 0.7 19:02:34 INFO - mozinfo == 0.9 19:02:34 INFO - mozleak == 0.1 19:02:34 INFO - mozlog == 3.1 19:02:34 INFO - moznetwork == 0.27 19:02:34 INFO - mozprocess == 0.22 19:02:34 INFO - mozprofile == 0.28 19:02:34 INFO - mozrunner == 6.11 19:02:34 INFO - mozscreenshot == 0.1 19:02:34 INFO - mozsystemmonitor == 0.0 19:02:34 INFO - moztest == 0.7 19:02:34 INFO - mozversion == 1.4 19:02:34 INFO - psutil == 3.1.1 19:02:34 INFO - requests == 1.2.3 19:02:34 INFO - wsgiref == 0.1.2 19:02:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:34 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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:34 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 19:02:34 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 19:02:34 INFO - Using env: {'DISPLAY': ':0', 19:02:34 INFO - 'HOME': '/home/cltbld', 19:02:34 INFO - 'LANG': 'en_US.UTF-8', 19:02:34 INFO - 'LANGUAGE': 'en_US:en', 19:02:34 INFO - 'LOGNAME': 'cltbld', 19:02:34 INFO - 'MAIL': '/var/mail/cltbld', 19:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:34 INFO - 'MOZ_NO_REMOTE': '1', 19:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:34 INFO - 'NO_EM_RESTART': '1', 19:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:34 INFO - 'PWD': '/builds/slave/test', 19:02:34 INFO - 'SHELL': '/bin/bash', 19:02:34 INFO - 'SHLVL': '1', 19:02:34 INFO - 'TERM': 'linux', 19:02:34 INFO - 'TMOUT': '86400', 19:02:34 INFO - 'USER': 'cltbld', 19:02:34 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:34 INFO - '_': '/tools/buildbot/bin/python'} 19:02:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 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)) 19:02:34 INFO - Cleaning up... 19:02:34 INFO - Return code: 0 19:02:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:34 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 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:34 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 19:02:34 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 19:02:34 INFO - Using env: {'DISPLAY': ':0', 19:02:34 INFO - 'HOME': '/home/cltbld', 19:02:34 INFO - 'LANG': 'en_US.UTF-8', 19:02:34 INFO - 'LANGUAGE': 'en_US:en', 19:02:34 INFO - 'LOGNAME': 'cltbld', 19:02:34 INFO - 'MAIL': '/var/mail/cltbld', 19:02:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:34 INFO - 'MOZ_NO_REMOTE': '1', 19:02:34 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:34 INFO - 'NO_EM_RESTART': '1', 19:02:34 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:34 INFO - 'PWD': '/builds/slave/test', 19:02:34 INFO - 'SHELL': '/bin/bash', 19:02:34 INFO - 'SHLVL': '1', 19:02:34 INFO - 'TERM': 'linux', 19:02:34 INFO - 'TMOUT': '86400', 19:02:34 INFO - 'USER': 'cltbld', 19:02:34 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:34 INFO - '_': '/tools/buildbot/bin/python'} 19:02:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:35 INFO - Downloading/unpacking jsonschema 19:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:02:37 INFO - Downloading/unpacking functools32 (from jsonschema) 19:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:37 INFO - Downloading functools32-3.2.3-2.tar.gz 19:02:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:02:37 INFO - warning: no files found matching '*.txt' 19:02:37 INFO - no previously-included directories found matching 'build' 19:02:37 INFO - no previously-included directories found matching 'dist' 19:02:37 INFO - no previously-included directories found matching '.git*' 19:02:37 INFO - Installing collected packages: jsonschema, functools32 19:02:37 INFO - Running setup.py install for functools32 19:02:37 INFO - warning: no files found matching '*.txt' 19:02:37 INFO - no previously-included directories found matching 'build' 19:02:37 INFO - no previously-included directories found matching 'dist' 19:02:37 INFO - no previously-included directories found matching '.git*' 19:02:37 INFO - Successfully installed jsonschema functools32 19:02:37 INFO - Cleaning up... 19:02:37 INFO - Return code: 0 19:02:37 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 19:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:02:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 19:02:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:02:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:02:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f6a3bf10f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x25808a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x264b770>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, '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': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', '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 19:02:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 19:02:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 19:02:37 INFO - Using env: {'DISPLAY': ':0', 19:02:37 INFO - 'HOME': '/home/cltbld', 19:02:37 INFO - 'LANG': 'en_US.UTF-8', 19:02:37 INFO - 'LANGUAGE': 'en_US:en', 19:02:37 INFO - 'LOGNAME': 'cltbld', 19:02:37 INFO - 'MAIL': '/var/mail/cltbld', 19:02:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:37 INFO - 'MOZ_NO_REMOTE': '1', 19:02:37 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:37 INFO - 'NO_EM_RESTART': '1', 19:02:37 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:37 INFO - 'PWD': '/builds/slave/test', 19:02:37 INFO - 'SHELL': '/bin/bash', 19:02:37 INFO - 'SHLVL': '1', 19:02:37 INFO - 'TERM': 'linux', 19:02:37 INFO - 'TMOUT': '86400', 19:02:37 INFO - 'USER': 'cltbld', 19:02:37 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:37 INFO - '_': '/tools/buildbot/bin/python'} 19:02:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:02:37 INFO - Downloading/unpacking flake8 19:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:02:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:02:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:02:39 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 19:02:39 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 19:02:39 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:39 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 19:02:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 19:02:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:02:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:02:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:02:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:02:40 INFO - no previously-included directories found matching 'docs/_build' 19:02:40 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 19:02:40 INFO - Running setup.py install for pep8 19:02:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 19:02:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 19:02:40 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 19:02:40 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 19:02:40 INFO - no previously-included directories found matching 'docs/_build' 19:02:40 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 19:02:40 INFO - Successfully installed flake8 mccabe pyflakes pep8 19:02:40 INFO - Cleaning up... 19:02:40 INFO - Return code: 0 19:02:40 INFO - Running post-action listener: _resource_record_post_action 19:02:40 INFO - Running post-action listener: _start_resource_monitoring 19:02:40 INFO - Starting resource monitoring. 19:02:40 INFO - ##### 19:02:40 INFO - ##### Finished create-virtualenv step (success) 19:02:40 INFO - ##### 19:02:40 INFO - ##### 19:02:40 INFO - ##### Running install step. 19:02:40 INFO - ##### 19:02:40 INFO - Running pre-action listener: _resource_record_pre_action 19:02:40 INFO - Running main action method: install 19:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:02:40 INFO - Reading from file tmpfile_stdout 19:02:40 INFO - Detecting whether we're running mozinstall >=1.0... 19:02:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:02:40 INFO - Reading from file tmpfile_stdout 19:02:40 INFO - Output received: 19:02:40 INFO - Usage: mozinstall [options] installer 19:02:40 INFO - Options: 19:02:40 INFO - -h, --help show this help message and exit 19:02:40 INFO - -d DEST, --destination=DEST 19:02:40 INFO - Directory to install application into. [default: 19:02:40 INFO - "/builds/slave/test"] 19:02:40 INFO - --app=APP Application being installed. [default: firefox] 19:02:40 INFO - mkdir: /builds/slave/test/build/application 19:02:40 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'] 19:02:40 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 19:02:57 INFO - Reading from file tmpfile_stdout 19:02:57 INFO - Output received: 19:02:57 INFO - /builds/slave/test/build/application/firefox/firefox 19:02:57 INFO - Running post-action listener: _resource_record_post_action 19:02:57 INFO - ##### 19:02:57 INFO - ##### Finished install step (success) 19:02:57 INFO - ##### 19:02:57 INFO - ##### 19:02:57 INFO - ##### Running run-tests step. 19:02:57 INFO - ##### 19:02:57 INFO - Running pre-action listener: _resource_record_pre_action 19:02:57 INFO - Running main action method: run_tests 19:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:02:57 INFO - Python 2.7.3 19:02:57 INFO - Return code: 0 19:02:57 INFO - grabbing minidump binary from tooltool 19:02: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')]} 19:02:57 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 0x26da9c0>, '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 0x26db8c0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26dbd50>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:02:57 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 19:02:57 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 19:02:57 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:02:57 INFO - Return code: 0 19:02:57 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 19:02:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:02:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:02:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 19:02:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:02:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 19:02:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 19:02:58 INFO - Return code: 0 19:02:58 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/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 19:02:58 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/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-003 --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 19:02:58 INFO - Using env: {'DISPLAY': ':0', 19:02:58 INFO - 'HOME': '/home/cltbld', 19:02:58 INFO - 'LANG': 'en_US.UTF-8', 19:02:58 INFO - 'LANGUAGE': 'en_US:en', 19:02:58 INFO - 'LOGNAME': 'cltbld', 19:02:58 INFO - 'MAIL': '/var/mail/cltbld', 19:02:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:02:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 19:02:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:02:58 INFO - 'MOZ_NO_REMOTE': '1', 19:02:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:02:58 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 19:02:58 INFO - 'NO_EM_RESTART': '1', 19:02:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 19:02:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:02:58 INFO - 'PWD': '/builds/slave/test', 19:02:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:02:58 INFO - 'SHELL': '/bin/bash', 19:02:58 INFO - 'SHLVL': '1', 19:02:58 INFO - 'TERM': 'linux', 19:02:58 INFO - 'TMOUT': '86400', 19:02:58 INFO - 'USER': 'cltbld', 19:02:58 INFO - 'XDG_SESSION_COOKIE': '7de08804244eb36560b44971000001c3-1462581191.140079-614059795', 19:02:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:02:58 INFO - '_': '/tools/buildbot/bin/python'} 19:02:58 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/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-003', '--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 19:02:58 INFO - mozversion application_buildid: 20160506163016 19:02:58 INFO - mozversion application_changeset: d50ac88f8feef390e875b3f92f6a69544107d6c1 19:02:58 INFO - mozversion application_display_name: Nightly 19:02:58 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:02:58 INFO - mozversion application_name: Firefox 19:02:58 INFO - mozversion application_remotingname: firefox 19:02:58 INFO - mozversion application_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:02:58 INFO - mozversion application_vendor: Mozilla 19:02:58 INFO - mozversion application_version: 49.0a1 19:02:58 INFO - mozversion platform_buildid: 20160506163016 19:02:58 INFO - mozversion platform_changeset: d50ac88f8feef390e875b3f92f6a69544107d6c1 19:02:58 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/mozilla-inbound 19:02:58 INFO - mozversion platform_version: 49.0a1 19:02:58 INFO - using testdate: 1462586578 19:02:58 INFO - actual date: 1462586578 19:02:58 INFO - starting webserver on 'localhost:44266' 19:02:58 INFO - SUITE-START | Running 2 tests 19:02:58 INFO - TEST-START | dromaeo_css 19:02:58 INFO - operating with platform_type : linux_ 19:02:58 INFO - Initialising browser for dromaeo_css test... 19:02:58 INFO - TEST-INFO | started process 28431 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTvxmO7/profile http://localhost:44266/getInfo.html) 19:03:03 INFO - PROCESS | 28431 | __metrics Screen width/height:1600/1200 19:03:03 INFO - PROCESS | 28431 | colorDepth:24 19:03:03 INFO - PROCESS | 28431 | Browser inner width/height: 1024/697 19:03:03 INFO - PROCESS | 28431 | __metrics 19:03:04 ERROR - PROCESS | 28431 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:03:05 INFO - TEST-INFO | 28431: exit 0 19:03:05 INFO - Browser initialized. 19:03:05 INFO - Running cycle 1/1 for dromaeo_css test... 19:03:05 INFO - TEST-INFO | started process 28513 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTvxmO7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:03:16 INFO - PROCESS | 28513 | RSS: Main: 152133632 19:03:16 INFO - PROCESS | 28513 | 19:04:08 INFO - PROCESS | 28513 | 1462586648294 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] 19:06:16 INFO - PROCESS | 28513 | Cycle 1(1): loaded http://localhost:44266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:44266/tests/dromaeo/cssquery-ext.html) 19:06:17 INFO - PROCESS | 28513 | RSS: Main: 243425280 19:06:17 INFO - PROCESS | 28513 | 19:09:19 INFO - PROCESS | 28513 | Cycle 1(1): loaded http://localhost:44266/tests/dromaeo/cssquery-ext.html (next: http://localhost:44266/tests/dromaeo/cssquery-jquery.html) 19:09:20 INFO - PROCESS | 28513 | RSS: Main: 238755840 19:09:20 INFO - PROCESS | 28513 | 19:12:22 INFO - PROCESS | 28513 | Cycle 1(1): loaded http://localhost:44266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:44266/tests/dromaeo/cssquery-mootools.html) 19:12:23 INFO - PROCESS | 28513 | RSS: Main: 239775744 19:12:23 INFO - PROCESS | 28513 | 19:15:25 INFO - PROCESS | 28513 | Cycle 1(1): loaded http://localhost:44266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:44266/tests/dromaeo/cssquery-prototype.html) 19:15:25 INFO - PROCESS | 28513 | RSS: Main: 229310464 19:15:25 INFO - PROCESS | 28513 | 19:18:28 INFO - PROCESS | 28513 | Cycle 1(1): loaded http://localhost:44266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:44266/tests/dromaeo/cssquery-yui.html) 19:18:28 INFO - PROCESS | 28513 | RSS: Main: 222380032 19:18:28 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | Cycle 1(1): loaded http://localhost:44266/tests/dromaeo/cssquery-yui.html (next: http://localhost:44266/tests/dromaeo/cssquery-dojo.html) 19:21:31 INFO - PROCESS | 28513 | RSS: Main: 276852736 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | __start_tp_report 19:21:31 INFO - PROCESS | 28513 | _x_x_mozilla_page_load 19:21:31 INFO - PROCESS | 28513 | _x_x_mozilla_page_load_details 19:21:31 INFO - PROCESS | 28513 | |i|pagename|runs| 19:21:31 INFO - PROCESS | 28513 | |0;dojo.html;3128.3899639570277;3149.6613939075887;3175.3173067790426;3177.4916013437855;3217.8551965161564;5270.499302566255;5300.125479295912;5352.598555108363;5370;5399.5680345572355;5316;5316.3354580677415;5337.492938170877;5392.865178370543;5405.243265942771;4772.308015337782;4872.488388719186;4878.390201224847;4881.975590122044;4972.105021096201;4899.216125419934;4917.06575750608;4927.211646136619;4955.504449555052;4996.275540046691;4724.24412094065;4784.665073444843;4813.903721925577;4841.8547443576745;4848.393950756155;4857.951420485785;4863.805447782084;4864.440589332236;4900.975495122537;4927.43334516528;4816.205326121186;4829.1548978928895;4830.73430961297;4835.202191638376;4882.292067650206;5165.328507294065;5175.301334319856;5184.948150518522;5209.531142197183;5240.056789777838;5664.2636457260705;5688.971555142198;5712.828615141552;5721.71391430431;5728.283964504437;5713.5429165667065;5759.424057594249;5764.682942438164;5777.24895763548;5781.768729250825;5232.790688372461;5257.894842103175;5264.447233040552;5304.336957880227;5337.492938170868;5698.943010569924;5763.567732420085;5769.711514424346;5770.105633626795;5771.797987070432;5428.7285635718845;5523.6409633373705;5563.471470210324;5569.944300556943;5576.804811831566;5577.330720313588;5640.238567793295;5641.097424412074;5644.6613203207935;5664.773409063674;5637.633553818989;5644.040513112781;5648.6610803351105;5649.519790817744;5654.830355089354;5409.594280428983;5571.860703482364;5633.155026745939;5636.18275350072;5701.230333904953;4359.433273674402;4370.847020354336;4387.583179597933;4392.494863090722;4430.734155950653;3913.099987003005;3923.43110249018;3926.391409331558;3929.1159489115294;3959.7822119783114;5201;5247.527722504993;5302.787888484495;5442.292501974718;5467.097928841697;5688.175214593944;5710.628809127394;5727.4845263926445;5731.512821410143;5764.423557644202;5345.438728933484;5352.571794256528;5357.678539287655;5366.436524164907;5368.5436737876935;4778.402699662543;4833.806647734122;4861.052094841542;4915.48387419323;4953.479884612136;5876.294844618587;5877.588568800228;5878.794242201502;5880.441358070976;5929.229200203946;5664.830055098354;5685.147227915846;5689.487946084872;5696.572757043155;5697.088465845445;5750.338711048115;5830.679312637845;5866.794662186803;5902.439268269508;5920.171176035273;5681.602287839812;5684.8294551163535;5689.658620482619;5690.174924635822;5694.515966142924;5708.686022268815;5731.799387021434;5755.050416681201;5764.13537969308;5778.046622307273;5383.919241211307;5510.504054635102;5512.5589952804485;5564.248826408545;5572.972135139298;5573.164025396223;5577.191307260506;5580.190872323409;5586.273784408001;5590.832275031756;5308.522232998894;5481;5485.9725701371235;5492.368377636623;5540.861478463071;5545.195946587642;5612.803551875665;5614.2701448811395;5672.177534257427;5682.488576028178;5512.366077900932;5512.614117011771;5520.06158952987;5525.033119204204;5567.749451274622 19:21:31 INFO - PROCESS | 28513 | |1;ext.html;638.2373064188293;649.4641920415659;650.9153810004699;658.1937127019402;662.7017841971115;42951.35572966404;44785;44863.551364486346;48751.26873096907;48788;47626.52373476264;48153;48211.758941205335;48295.55113346597;48343.75828120863;8977.371583989123;9158.404703694272;9176.770580735469;9179.678711245107;9182.908170918272;8989.235914947238;9032.232260257886;9071.909280907206;9075;9077.546122693871;9071.909280907206;9111.72664820053;9117.95441022794;9118.133777291147;9121.224695900857;9081.18269355758;9111.954440227822;9114.908850911472;9115.179633832953;9117.954410227972;8920.643174273086;8947.50788706621;9001.414908031022;9005.864912026325;9008.864867027;41397;41523.7923810379;41690.79154604208;41864;41871.37192942108;3474.9131271718156;3759.4924685167425;3963.326234540135;4161.146964872194;4219.535851056381;31804;31931.201719956956;31975;32347.676523234702;32425.351492970007;4075.286824805647;4091.468109145822;4195.811188496495;4198.097409057067;4248.214080395137;314792;318146.9999999977;318339;318474;321196.9999999977;62885.37114628795;63494.36505634977;63726.681366593795;63999;64000.67999659972;58264.70867645634;64136;66847.33152668411;66872.33127668759;66905.6654716733;16425.09661968579;17166.570835729206;17174;17222.741658875126;17425.39011134604;13382.197068175747;13518.662033449242;13525.053246272862;13531.39108740109;13536.729265414637;17958.461246162635;18292.817071829377;19589.902050489658;19751;19806.90096549508;7147.785566433015;7238.420926325882;7241.5293005954445;7260.41916646667;7448.255174482617;990.1979396688705;1013.1793247469574;1108.8780234174235;1191.141818762733;1192.8747481514315;29642.85178574093;32824.34351313008;32977.83511082477;33124.834375828446;33453;1134.982975255372;1216.7444836584239;1224.088054399478;1248.825164476974;1320.8695391921208;17435.389761358547;17533.736993945102;17653.470395888144;17656.117194140497;17720.55698607545;496459;497218;498061;500920;501755;41109.58890411058;41375.379369309485;41697.37453938133;42022.36966445506;42040.78979605083;1404.1434724817852;1410.7672234081263;1443.7112577484545;1453.876420504269;1465.4724299252473;30726.38547228998;31325;31384.843075784476;31616.683833161373;32651.510227346134;51048.744756275984;52024;52044;52075.2188717162;52093.47906520886;439244;447302;447904;448275;448560;26135.215943521725;26879.731202687723;26938.057167999024;29285.267868303465;29763.553546696385;42369.36445953375;50126.49873501218;55030;55129.72435137798;56026.43973560212;42177.36733948932;43610.563894360654;43655.126897462505;43705;44221;42447.5755242456;42546.36180457358;42834.571654283056;43022.35466468068;43075.35386969135;42501;43839.78080109579;44022.77988610036;44491.332630011224;44514.55485445104;36014.639853601126;37253.813730932256;37553;37779.81110094432;38864;28788.85605571959;28876.566851496824;28897.711022890344;29036.854815725786;29253.268668283465 19:21:31 INFO - PROCESS | 28513 | |2;jquery.html;387.9379299312107;397.9422983667366;414.0848724319255;414.94190813286144;431.8812326610182;12027.819582706266;15248.161351125684;18870.811291887094;18940.147693353814;18960.146793394295;19537.70693439596;20012.0994555245;20455.795442045575;20733.274335398262;20767.376978690627;6412.839679008015;6480.902786458207;6607.240167380741;6621.139251897247;6626.337366263383;6262.624242545439;6438.903416448734;6443.452306553949;6446.323136070715;6485.772997945073;6388.169537960059;6393.264774550939;6495.805125846208;6497.05792660064;6574.737010519598;6425.903611445832;6524.967375163117;6533.118029066087;6547.377999090103;6616.900746488806;6171.814845554595;6360.586561873463;6384.074309225182;6405.327440618761;6410.8717825643225;24715;24867.253982380556;25938;26051.478970420674;26052.218433447026;2412.155745489082;2524.0913271222344;2626.6060090986316;3110.8289044102567;3389.4237979543536;15389.153596552185;15455.690886182214;15655.686886262325;16067.598310042225;16164.75752863708;2085.8957052147484;2131.509752756859;2133.189388032538;2208.2491952736145;2255.07541907817;88664.55667721685;92859.53570232174;93256.53371733165;93467.53266233693;93812.53093734555;17100.230489627997;17563.20965556553;17821.198046087957;17841.821581784276;18080.819191808176;32000.199994999846;32083;32164.35671286561;32344;32381.409487241435;9938.900610993798;10167.4916254187;10202.64290749835;10240.743981400525;10426.5829366826;8480.830383392298;8530.14698530142;8563.272121869712;8703.129687031373;8715.564221788885;8592.355573332025;8772.473651580925;8805.207531322212;8855.114488551222;8936.32977526688;3209.326041531305;3250.837458127084;3262.2986057997646;3401.8469168886963;3520.982395088008;907.2651152566442;1007.0131271354114;1023.2837014090167;1040.921930855189;1055.4089709762557;18419;18816.435506934813;18911.3381031666;18912.43262702121;19210.327638532865;1013.189448441244;1039.734867608755;1046.0480962324248;1056.1920131099714;1064.4252103863982;10357.326773759682;10567.524461399256;10994.560217591366;11261.549538018551;11748.941255293668;457070;457613;457837;458141;471967;51958.22062669063;62681;90257.09742902618;91688.54155729178;91947;1090.7818436312768;1112.22700223344;1159.5361855257963;1161.4599211366756;1177.0936378988301;17369.13154342303;17489.650206995535;17681.115944202997;17733.911330443265;18016.189271483076;38477;38956.6104338953;39498.41383851145;40764.79617601893;40875.591244087176;51425.48574514357;52526.737366312926;58117;58181.41818581929;58317.70841145767;7400.703971841066;7798.532088074507;7875.251851074138;8663.26362259215;9006.369554131148;9756.560954757195;11473.598424055119;11885.524579016743;12337.444814983168;12443.875561244271;10630.30902991303;11018.338899666045;11407.201495895208;11645.59240426581;11901.345426001306;11619.883801161881;11705.122115841175;11818.645440636794;11832.70418239551;11861.940690296493;11617.244879083171;11820.468078936297;11990.280583165038;12024.819627705767;12120.969717574106;7956.5226086435;7995.320397766255;8012.399070069651;8013.118556958846;8066.23370779775;8874.068222836768;10835.67492975212;11275.718107047061;11421;11659.76680466369 19:21:31 INFO - PROCESS | 28513 | |3;mootools.html;1284.1332100831949;1508.502194275889;1511.2821409830328;1681.3862940026886;1841.806610305918;8063.677452901877;8461.619227134779;8488.61801218946;8647.351448641346;8712.128787121284;8433.198846109624;8603.91396086039;8611.56942152893;8612.052674205832;8637.39538232324;2043.3359158273547;2260.660900864867;2285.462916214688;2320.709911261092;2329.231353653299;2340.9531809363802;2346.8474549154334;2351.3886389538757;2353.0234952494693;2353.0705371378294;2227.3318004598636;2287.7254729432493;2291.633338665814;2292.025888997178;2297.1959814065026;1981.8513611479125;2241.7197850268635;2306.112146823485;2308.284431826139;2318.3392733070996;2271.1029143488395;2275.9089636414687;2278.6809846621486;2279.7606251343536;2321.5124823787023;8655.350848686374;8660.350473714434;8677.088905664878;8684.305255579538;8724.38929274951;5125.538701516844;5286.15421532553;5291.3385826771655;5325;5328.6802791832615;3273.2307907641684;3370.7808992415416;3374.5275661407422;3399.0312760863035;3437.9312413751586;1139.4587570903836;1158.3629004047825;1160.8955194032494;1174.653477224221;1186.1933884958225;12063.879361206451;12135.150539462153;12137.635870923887;12138.514459421702;12142.271463712206;4646.442426908792;4763.214069678534;4809.831655892027;4816.831410900601;4852.902941941142;10163.898361016296;10171.084602385672;10171.186305095574;10175.44035078063;10196;4749.596284315872;5312.2297266896085;5324.520793128637;5336.67979921206;5342.2253773202665;4609.308603709471;4741.193997020515;4754.025424787979;4784.378030855966;4829;3928.175083232496;3950.9012274692786;3979.3036218662196;4024.7786371749835;4047.5547689754103;1273.9490420383265;1300.245857402723;1307.9346032698327;1319.6502926724427;1327.462377737018;969.9078587534173;985.8126955878361;1002.4135880509838;1005.7222125888763;1006.8137394582026;5767.913481297784;6054.666993315322;6092.055731361646;6134.693265336716;6290.433860952537;906.6735975048977;917.7980844214262;954.174638937328;1011.1051719228535;1011.8077565262577;3784.7539909905768;3853.3641949078087;3920.3923391874305;3926.646601805794;3937.684985201177;10167.44079075658;10230.693079207636;10436.478176091165;10476.580936762597;10486.947565262124;10165.085142337226;10363.63727269542;10368.637097701545;10389.272750907516;10659.147268218523;7998.680052797823;8075.676972921017;8133.959330203429;8191.0580283267;8257.174282571816;6005.669688167018;6166.691665416801;6248;6292.433680968735;6316.62100273985;9605.903940960781;9995.999999999709;10165.898341016495;10214.795704086026;10254.743631409274;9939.254555908481;10234.488275586042;10320.896791031993;10335.431551264752;10343.431111288952;6985.126859142607;7554;7561.357284630868;7676.731314403969;7716.8456630868195;8531.061583225965;8534.359923005675;8543.273821724973;8581.313494920516;8605.053444121268;7503.737369192052;7663.195364486872;7667.463277570517;7683.039620047494;7776.261255180749;7381.520201186906;7442.7395041173295;7475.626218688935;7554.848903022019;7563.659635316314;7001.334873187039;7025.754098606524;7085.751998680022;7091.680874360563;7108.573485590881;4838.854834354975;5123.129068058513;5225;5230.63385563007;5294.867628309323;5270.262163297218;5355.277037599874;5436.483534064258;5443.510084092292;5446.700431476307 19:21:31 INFO - PROCESS | 28513 | |4;prototype.html;339.4890689512284;339.8674516938394;346.77113105350463;350.4550424090538;351.5991769382904;5169.172932330828;5180.300659410983;5182.4299327073995;5200.671570195078;5314.548263397616;5184.248283998818;5190.662606930557;5204.906969536401;5228.241904923784;5235.424103348629;3913.236918800842;3937.310970580137;4096.754194748325;4108.506979162505;4140.37894315852;4079.0822065035277;4087.366458198969;4108.8356465741335;4122.649574786146;4151.211269858717;3883.9805800970958;4045.797710114497;4077.408775727518;4078.2659121358142;4082.4080508326274;3818.6443812446737;3997.34043882759;4008.398740188966;4070.5725898780793;4091.729090354852;3615.9096022599383;3625;3645.398509245971;3651.2514934438377;3652.7032903319205;4964.230544265644;4979.701217926936;4997.001798920654;5019.42276638184;5021.121303771825;4525.479569849444;4598.402207713009;4651.232546629836;4672.252439609646;4686.27362758773;5395.70323632204;5405.135178371442;5412.431694672042;5429.755660995264;5433.592480563973;4001.2597669431248;4008.83964641414;4013.819378127991;4018.4374187613757;4040.454538637305;5343.572514198855;5636.2954630671165;5658.490735833753;5672.886542269172;5696.287964004458;3982.9601703983167;3988.3618621020496;3998;4002.6197511236387;4027;4062.1469491406547;4063.146739184745;4065.6137666921595;4070.5725898780793;4094.754314741125;4455.4876189238;4642.9535704643195;4650.720956742606;4675.602573781267;4688.8124475021295;5496.087073850866;5508.862278443071;5521.171824226398;5542.806001789918;5546.500814926676;1347.47448495087;1365.0581099041628;1369.5275130080106;1370.7464119137992;1376.7040086381276;909.4634165842185;912.4160537256162;944.6740874398322;952.1668540027476;956.8995255498212;5159.277701121846;5278.630495865253;5282.656627319212;5295.4969277918535;5317.787288508416;5487.847552014122;5532.834014979477;5554.72226388679;5635.182898479779;5674.4892959633835;4959.404871415453;5281.788728450896;5331.4935081168005;5343.812966546152;5366.060939335601;2871.3826527296733;2884.77397106229;2893.9276518086795;2908.8109272897195;2954.9113526594238;5706.229658995982;5724.255846739898;5736.971315143398;5752;5762.683052432157;5537.889242215214;5579.7489112990015;5626.493615574536;5629.748100759729;5652.236948011966;5576.498115169573;5587.804426845041;5621.409751975983;5622.453825198021;5673.858153546194;5264.131233412237;5477.616566840283;5480.287562616834;5483.479069488392;5538.030844602259;5622.437756224344;5647.774089036392;5650.830475085754;5650.915236271377;5651.378348381757;5806.390329015561;5818.301803783573;5859.94140058594;5893.233879595625;5901.557383196363;3838.466612036043;4053.2096241232675;4058.776767277828;4128.4632997710105;4137.97931010343;5595.440455954372;5601.467860553241;5604.159376093618;5660.377358490645;5665.433456654301;5441.911617676401;5445.720255739977;5447.264619276347;5483.917741233805;5508.889822203613;5208.296879921162;5420.349558053059;5420.75606597712;5424.349078110652;5448.782048718007;5163.561097306771;5346.411894691503;5353.544948679406;5372.086745253395;5385.868967516865;5340.866478338073;5455.263539422127;5457.754401052041;5470.261514695465;5472.781088756406;5390.218418329242;5409.161579955114;5411.83764487066;5417.377001644862;5438.960389853804 19:21:31 INFO - PROCESS | 28513 | |5;yui.html;764.5718397697293;772.6600295869816;841.600239886054;864.1833467373333;872.9781755456113;12588.244705317677;13241.999999999987;13299.601011969633;13315;14788.704225915475;12765.808512872314;12988.54540091097;13016.60950171494;13025;13038.869611303884;690.5925503952667;805.5005896344275;821.5686764448652;824.1099612418603;832.1927730101822;847.8516259654566;848.6690190825583;850.4429598612918;851.2338894994492;851.3019324154196;880.5685214245005;881.422668152358;881.4226681523612;881.5460038080362;881.6076845803588;716.931891470312;864.0365991918997;887.6981826179066;887.8579427291667;891.1489527501285;647.3138473218356;649.0102593544834;649.3668673043792;649.3863299182275;649.5226008883515;12981.156224845356;12990.610281691565;12991.870081299161;13018.088733788638;13038.608841734764;13687.452501899912;13972.650683732887;14066.71866562663;14076.92961535196;14197.432102715879;11427.200095993283;11699.18105732599;11782.587609433629;12018.098642601839;12022.341700694842;4467.195904737146;4480.6191473724775;4495.5953964143055;4546.454425468932;4653.976730116327;56175;56197.71901140468;57275.42724572784;57903.13145302824;58169.41830581682;10510.632127875488;10596.470176491144;10869.184811139037;10882.455877206108;11099.112071034298;19168.13743381551;19316.227350906087;19324.1304141314;19335.226590936487;19386.5153371164;7890.8816367754525;8035.437519373705;8200.753977380688;8359.916400835913;8397.874031889527;4599.655025873073;4610.9308360374625;4614.861554153381;4639.884002899955;4724.196886529298;3273.85267662951;3355.0605830367535;3357.378884906251;3358.294758100826;3364.175776934667;584.6959581017846;667.7395815631908;689.2039694153261;695.5583204664973;703.5673061067407;581.6946103295803;642.3768944124192;676.7428377216725;725.0719079578148;728.7121586973116;9381.624735010522;9749.415035097916;9765.121139097373;9777.266704997153;9872.506374681381;625.3590070178104;690.602903330587;746.932776050158;756.8675481790665;781.160252728308;4523.212793889504;4644.233701439292;4673.112108699336;4709.67032307742;4740.597049250852;44530.777346113064;44806.10387792291;54512;56963.14555281674;67847;56009;57176.400358172985;57399.71300143473;57710;58034.419655803744;52730.20904686433;52996.47003529993;53058.46941530613;53350.4664953361;53448.73275633597;9561.187299079656;9596.23230141601;9721.659741909;9764;9769.511524423893;33150.83424582861;33887.491687625195;34312.828435857664;34838.30323393569;35569.1107722309;45575.088498230514;46460.53539464562;46635.53364466448;46638;46856.297155543376;12889.291088989821;12939.094263401472;13017.739645207232;13069.673258168621;13139.54011609589;13597.796033059314;13743.587692369245;14178.716425671222;14441.927790360982;14443.78334324965;13338.799918001043;13510.527131550349;13676.931615342257;13860.514881979083;14180.787288190892;13113.541026064042;13248.337583120998;13614.523491677744;13917.373718182504;14046.78929816033;13253.602391928258;13266.204027758366;13406.128601640861;13998.440062397798;14104.929475352557;10122.443265620166;10688.358698478116;10724;10988.780224395627;11245.212835101463;11216.439178041228;11221;11305.378204198849;11319.320840749582;11505.367204803817 19:21:31 INFO - PROCESS | 28513 | __end_tp_report 19:21:31 INFO - PROCESS | 28513 | __start_cc_report 19:21:31 INFO - PROCESS | 28513 | _x_x_mozilla_cycle_collect,914 19:21:31 INFO - PROCESS | 28513 | __end_cc_report 19:21:31 INFO - PROCESS | 28513 | __startTimestamp1462587691927__endTimestamp 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | ------- Summary: start ------- 19:21:31 INFO - PROCESS | 28513 | Number of tests: 6 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | [#0] dojo.html Cycles:165 Average:5286.90 Median:5510.50 stddev:580.36 (10.5%) stddev-sans-first:556.89 19:21:31 INFO - PROCESS | 28513 | Values: 3128.4 3149.7 3175.3 3177.5 3217.9 5270.5 5300.1 5352.6 5370.0 5399.6 5316.0 5316.3 5337.5 5392.9 5405.2 4772.3 4872.5 4878.4 4882.0 4972.1 4899.2 4917.1 4927.2 4955.5 4996.3 4724.2 4784.7 4813.9 4841.9 4848.4 4858.0 4863.8 4864.4 4901.0 4927.4 4816.2 4829.2 4830.7 4835.2 4882.3 5165.3 5175.3 5184.9 5209.5 5240.1 5664.3 5689.0 5712.8 5721.7 5728.3 5713.5 5759.4 5764.7 5777.2 5781.8 5232.8 5257.9 5264.4 5304.3 5337.5 5698.9 5763.6 5769.7 5770.1 5771.8 5428.7 5523.6 5563.5 5569.9 5576.8 5577.3 5640.2 5641.1 5644.7 5664.8 5637.6 5644.0 5648.7 5649.5 5654.8 5409.6 5571.9 5633.2 5636.2 5701.2 4359.4 4370.8 4387.6 4392.5 4430.7 3913.1 3923.4 3926.4 3929.1 3959.8 5201.0 5247.5 5302.8 5442.3 5467.1 5688.2 5710.6 5727.5 5731.5 5764.4 5345.4 5352.6 5357.7 5366.4 5368.5 4778.4 4833.8 4861.1 4915.5 4953.5 5876.3 5877.6 5878.8 5880.4 5929.2 5664.8 5685.1 5689.5 5696.6 5697.1 5750.3 5830.7 5866.8 5902.4 5920.2 5681.6 5684.8 5689.7 5690.2 5694.5 5708.7 5731.8 5755.1 5764.1 5778.0 5383.9 5510.5 5512.6 5564.2 5573.0 5573.2 5577.2 5580.2 5586.3 5590.8 5308.5 5481.0 5486.0 5492.4 5540.9 5545.2 5612.8 5614.3 5672.2 5682.5 5512.4 5512.6 5520.1 5525.0 5567.7 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | [#1] ext.html Cycles:180 Average:59040.56 Median:23347.89 stddev:113198.67 (484.8%) stddev-sans-first:113431.29 19:21:31 INFO - PROCESS | 28513 | Values: 638.2 649.5 650.9 658.2 662.7 42951.4 44785.0 44863.6 48751.3 48788.0 47626.5 48153.0 48211.8 48295.6 48343.8 8977.4 9158.4 9176.8 9179.7 9182.9 8989.2 9032.2 9071.9 9075.0 9077.5 9071.9 9111.7 9118.0 9118.1 9121.2 9081.2 9112.0 9114.9 9115.2 9118.0 8920.6 8947.5 9001.4 9005.9 9008.9 41397.0 41523.8 41690.8 41864.0 41871.4 3474.9 3759.5 3963.3 4161.1 4219.5 31804.0 31931.2 31975.0 32347.7 32425.4 4075.3 4091.5 4195.8 4198.1 4248.2 314792.0 318147.0 318339.0 318474.0 321197.0 62885.4 63494.4 63726.7 63999.0 64000.7 58264.7 64136.0 66847.3 66872.3 66905.7 16425.1 17166.6 17174.0 17222.7 17425.4 13382.2 13518.7 13525.1 13531.4 13536.7 17958.5 18292.8 19589.9 19751.0 19806.9 7147.8 7238.4 7241.5 7260.4 7448.3 990.2 1013.2 1108.9 1191.1 1192.9 29642.9 32824.3 32977.8 33124.8 33453.0 1135.0 1216.7 1224.1 1248.8 1320.9 17435.4 17533.7 17653.5 17656.1 17720.6 496459.0 497218.0 498061.0 500920.0 501755.0 41109.6 41375.4 41697.4 42022.4 42040.8 1404.1 1410.8 1443.7 1453.9 1465.5 30726.4 31325.0 31384.8 31616.7 32651.5 51048.7 52024.0 52044.0 52075.2 52093.5 439244.0 447302.0 447904.0 448275.0 448560.0 26135.2 26879.7 26938.1 29285.3 29763.6 42369.4 50126.5 55030.0 55129.7 56026.4 42177.4 43610.6 43655.1 43705.0 44221.0 42447.6 42546.4 42834.6 43022.4 43075.4 42501.0 43839.8 44022.8 44491.3 44514.6 36014.6 37253.8 37553.0 37779.8 38864.0 28788.9 28876.6 28897.7 29036.9 29253.3 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | [#2] jquery.html Cycles:180 Average:29088.43 Median:32041.60 stddev:75901.02 (236.9%) stddev-sans-first:76083.35 19:21:31 INFO - PROCESS | 28513 | Values: 387.9 397.9 414.1 414.9 431.9 12027.8 15248.2 18870.8 18940.1 18960.1 19537.7 20012.1 20455.8 20733.3 20767.4 6412.8 6480.9 6607.2 6621.1 6626.3 6262.6 6438.9 6443.5 6446.3 6485.8 6388.2 6393.3 6495.8 6497.1 6574.7 6425.9 6525.0 6533.1 6547.4 6616.9 6171.8 6360.6 6384.1 6405.3 6410.9 24715.0 24867.3 25938.0 26051.5 26052.2 2412.2 2524.1 2626.6 3110.8 3389.4 15389.2 15455.7 15655.7 16067.6 16164.8 2085.9 2131.5 2133.2 2208.2 2255.1 88664.6 92859.5 93256.5 93467.5 93812.5 17100.2 17563.2 17821.2 17841.8 18080.8 32000.2 32083.0 32164.4 32344.0 32381.4 9938.9 10167.5 10202.6 10240.7 10426.6 8480.8 8530.1 8563.3 8703.1 8715.6 8592.4 8772.5 8805.2 8855.1 8936.3 3209.3 3250.8 3262.3 3401.8 3521.0 907.3 1007.0 1023.3 1040.9 1055.4 18419.0 18816.4 18911.3 18912.4 19210.3 1013.2 1039.7 1046.0 1056.2 1064.4 10357.3 10567.5 10994.6 11261.5 11748.9 457070.0 457613.0 457837.0 458141.0 471967.0 51958.2 62681.0 90257.1 91688.5 91947.0 1090.8 1112.2 1159.5 1161.5 1177.1 17369.1 17489.7 17681.1 17733.9 18016.2 38477.0 38956.6 39498.4 40764.8 40875.6 51425.5 52526.7 58117.0 58181.4 58317.7 7400.7 7798.5 7875.3 8663.3 9006.4 9756.6 11473.6 11885.5 12337.4 12443.9 10630.3 11018.3 11407.2 11645.6 11901.3 11619.9 11705.1 11818.6 11832.7 11861.9 11617.2 11820.5 11990.3 12024.8 12121.0 7956.5 7995.3 8012.4 8013.1 8066.2 8874.1 10835.7 11275.7 11421.0 11659.8 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | [#3] mootools.html Cycles:180 Average:5747.10 Median:4751.81 stddev:3217.90 (67.7%) stddev-sans-first:3209.45 19:21:31 INFO - PROCESS | 28513 | Values: 1284.1 1508.5 1511.3 1681.4 1841.8 8063.7 8461.6 8488.6 8647.4 8712.1 8433.2 8603.9 8611.6 8612.1 8637.4 2043.3 2260.7 2285.5 2320.7 2329.2 2341.0 2346.8 2351.4 2353.0 2353.1 2227.3 2287.7 2291.6 2292.0 2297.2 1981.9 2241.7 2306.1 2308.3 2318.3 2271.1 2275.9 2278.7 2279.8 2321.5 8655.4 8660.4 8677.1 8684.3 8724.4 5125.5 5286.2 5291.3 5325.0 5328.7 3273.2 3370.8 3374.5 3399.0 3437.9 1139.5 1158.4 1160.9 1174.7 1186.2 12063.9 12135.2 12137.6 12138.5 12142.3 4646.4 4763.2 4809.8 4816.8 4852.9 10163.9 10171.1 10171.2 10175.4 10196.0 4749.6 5312.2 5324.5 5336.7 5342.2 4609.3 4741.2 4754.0 4784.4 4829.0 3928.2 3950.9 3979.3 4024.8 4047.6 1273.9 1300.2 1307.9 1319.7 1327.5 969.9 985.8 1002.4 1005.7 1006.8 5767.9 6054.7 6092.1 6134.7 6290.4 906.7 917.8 954.2 1011.1 1011.8 3784.8 3853.4 3920.4 3926.6 3937.7 10167.4 10230.7 10436.5 10476.6 10486.9 10165.1 10363.6 10368.6 10389.3 10659.1 7998.7 8075.7 8134.0 8191.1 8257.2 6005.7 6166.7 6248.0 6292.4 6316.6 9605.9 9996.0 10165.9 10214.8 10254.7 9939.3 10234.5 10320.9 10335.4 10343.4 6985.1 7554.0 7561.4 7676.7 7716.8 8531.1 8534.4 8543.3 8581.3 8605.1 7503.7 7663.2 7667.5 7683.0 7776.3 7381.5 7442.7 7475.6 7554.8 7563.7 7001.3 7025.8 7085.8 7091.7 7108.6 4838.9 5123.1 5225.0 5230.6 5294.9 5270.3 5355.3 5436.5 5443.5 5446.7 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | [#4] prototype.html Cycles:180 Average:4593.40 Median:5282.22 stddev:1344.74 (25.5%) stddev-sans-first:1310.06 19:21:31 INFO - PROCESS | 28513 | Values: 339.5 339.9 346.8 350.5 351.6 5169.2 5180.3 5182.4 5200.7 5314.5 5184.2 5190.7 5204.9 5228.2 5235.4 3913.2 3937.3 4096.8 4108.5 4140.4 4079.1 4087.4 4108.8 4122.6 4151.2 3884.0 4045.8 4077.4 4078.3 4082.4 3818.6 3997.3 4008.4 4070.6 4091.7 3615.9 3625.0 3645.4 3651.3 3652.7 4964.2 4979.7 4997.0 5019.4 5021.1 4525.5 4598.4 4651.2 4672.3 4686.3 5395.7 5405.1 5412.4 5429.8 5433.6 4001.3 4008.8 4013.8 4018.4 4040.5 5343.6 5636.3 5658.5 5672.9 5696.3 3983.0 3988.4 3998.0 4002.6 4027.0 4062.1 4063.1 4065.6 4070.6 4094.8 4455.5 4643.0 4650.7 4675.6 4688.8 5496.1 5508.9 5521.2 5542.8 5546.5 1347.5 1365.1 1369.5 1370.7 1376.7 909.5 912.4 944.7 952.2 956.9 5159.3 5278.6 5282.7 5295.5 5317.8 5487.8 5532.8 5554.7 5635.2 5674.5 4959.4 5281.8 5331.5 5343.8 5366.1 2871.4 2884.8 2893.9 2908.8 2954.9 5706.2 5724.3 5737.0 5752.0 5762.7 5537.9 5579.7 5626.5 5629.7 5652.2 5576.5 5587.8 5621.4 5622.5 5673.9 5264.1 5477.6 5480.3 5483.5 5538.0 5622.4 5647.8 5650.8 5650.9 5651.4 5806.4 5818.3 5859.9 5893.2 5901.6 3838.5 4053.2 4058.8 4128.5 4138.0 5595.4 5601.5 5604.2 5660.4 5665.4 5441.9 5445.7 5447.3 5483.9 5508.9 5208.3 5420.3 5420.8 5424.3 5448.8 5163.6 5346.4 5353.5 5372.1 5385.9 5340.9 5455.3 5457.8 5470.3 5472.8 5390.2 5409.2 5411.8 5417.4 5439.0 19:21:31 INFO - PROCESS | 28513 | 19:21:31 INFO - PROCESS | 28513 | [#5] yui.html Cycles:180 Average:14973.84 Median:4627.37 stddev:17078.93 (369.1%) stddev-sans-first:17093.51 19:21:31 INFO - PROCESS | 28513 | Values: 764.6 772.7 841.6 864.2 873.0 12588.2 13242.0 13299.6 13315.0 14788.7 12765.8 12988.5 13016.6 13025.0 13038.9 690.6 805.5 821.6 824.1 832.2 847.9 848.7 850.4 851.2 851.3 880.6 881.4 881.4 881.5 881.6 716.9 864.0 887.7 887.9 891.1 647.3 649.0 649.4 649.4 649.5 12981.2 12990.6 12991.9 13018.1 13038.6 13687.5 13972.7 14066.7 14076.9 14197.4 11427.2 11699.2 11782.6 12018.1 12022.3 4467.2 4480.6 4495.6 4546.5 4654.0 56175.0 56197.7 57275.4 57903.1 58169.4 10510.6 10596.5 10869.2 10882.5 11099.1 19168.1 19316.2 19324.1 19335.2 19386.5 7890.9 8035.4 8200.8 8359.9 8397.9 4599.7 4610.9 4614.9 4639.9 4724.2 3273.9 3355.1 3357.4 3358.3 3364.2 584.7 667.7 689.2 695.6 703.6 581.7 642.4 676.7 725.1 728.7 9381.6 9749.4 9765.1 9777.3 9872.5 625.4 690.6 746.9 756.9 781.2 4523.2 4644.2 4673.1 4709.7 4740.6 44530.8 44806.1 54512.0 56963.1 67847.0 56009.0 57176.4 57399.7 57710.0 58034.4 52730.2 52996.5 53058.5 53350.5 53448.7 9561.2 9596.2 9721.7 9764.0 9769.5 33150.8 33887.5 34312.8 34838.3 35569.1 45575.1 46460.5 46635.5 46638.0 46856.3 12889.3 12939.1 13017.7 13069.7 13139.5 13597.8 13743.6 14178.7 14441.9 14443.8 13338.8 13510.5 13676.9 13860.5 14180.8 13113.5 13248.3 13614.5 13917.4 14046.8 13253.6 13266.2 13406.1 13998.4 14104.9 10122.4 10688.4 10724.0 10988.8 11245.2 11216.4 11221.0 11305.4 11319.3 11505.4 19:21:31 INFO - PROCESS | 28513 | -------- Summary: end -------- 19:21:31 INFO - PROCESS | 28513 | 19:21:32 INFO - TEST-INFO | 28513: exit 0 19:21:32 INFO - TEST-OK | dromaeo_css | took 1114033ms 19:21:32 INFO - TEST-START | kraken 19:21:32 INFO - operating with platform_type : linux_ 19:21:32 INFO - Initialising browser for kraken test... 19:21:32 INFO - TEST-INFO | started process 28702 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnOJti5/profile http://localhost:44266/getInfo.html) 19:21:36 INFO - PROCESS | 28702 | __metrics Screen width/height:1600/1200 19:21:36 INFO - PROCESS | 28702 | colorDepth:24 19:21:36 INFO - PROCESS | 28702 | Browser inner width/height: 1024/697 19:21:36 INFO - PROCESS | 28702 | __metrics 19:21:38 ERROR - PROCESS | 28702 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 19:21:38 INFO - TEST-INFO | 28702: exit 0 19:21:38 INFO - Browser initialized. 19:21:38 INFO - Running cycle 1/1 for kraken test... 19:21:38 INFO - TEST-INFO | started process 28783 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpnOJti5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:21:50 INFO - PROCESS | 28783 | RSS: Main: 152719360 19:21:50 INFO - PROCESS | 28783 | 19:21:53 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:44266/tests/kraken/driver.html?testName=audio-beat-detection) 19:21:54 INFO - PROCESS | 28783 | RSS: Main: 208654336 19:21:54 INFO - PROCESS | 28783 | 19:21:56 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:44266/tests/kraken/driver.html?testName=audio-dft) 19:21:57 INFO - PROCESS | 28783 | RSS: Main: 196632576 19:21:57 INFO - PROCESS | 28783 | 19:22:00 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:44266/tests/kraken/driver.html?testName=audio-fft) 19:22:01 INFO - PROCESS | 28783 | RSS: Main: 264962048 19:22:01 INFO - PROCESS | 28783 | 19:22:03 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:44266/tests/kraken/driver.html?testName=audio-oscillator) 19:22:03 INFO - PROCESS | 28783 | RSS: Main: 174170112 19:22:03 INFO - PROCESS | 28783 | 19:22:06 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:44266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:22:06 INFO - PROCESS | 28783 | RSS: Main: 178655232 19:22:06 INFO - PROCESS | 28783 | 19:22:09 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:44266/tests/kraken/driver.html?testName=imaging-darkroom) 19:22:10 INFO - PROCESS | 28783 | RSS: Main: 214765568 19:22:10 INFO - PROCESS | 28783 | 19:22:13 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:44266/tests/kraken/driver.html?testName=imaging-desaturate) 19:22:14 INFO - PROCESS | 28783 | RSS: Main: 215232512 19:22:14 INFO - PROCESS | 28783 | 19:22:16 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:44266/tests/kraken/driver.html?testName=json-parse-financial) 19:22:17 INFO - PROCESS | 28783 | RSS: Main: 215470080 19:22:17 INFO - PROCESS | 28783 | 19:22:19 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:44266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:22:19 INFO - PROCESS | 28783 | RSS: Main: 202670080 19:22:19 INFO - PROCESS | 28783 | 19:22:21 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:22:21 INFO - PROCESS | 28783 | RSS: Main: 207036416 19:22:21 INFO - PROCESS | 28783 | 19:22:23 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:22:24 INFO - PROCESS | 28783 | RSS: Main: 216948736 19:22:24 INFO - PROCESS | 28783 | 19:22:27 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:22:27 INFO - PROCESS | 28783 | RSS: Main: 222437376 19:22:27 INFO - PROCESS | 28783 | 19:22:30 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:22:31 INFO - PROCESS | 28783 | RSS: Main: 218189824 19:22:31 INFO - PROCESS | 28783 | 19:22:32 INFO - PROCESS | 28783 | Cycle 1(1): loaded http://localhost:44266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:44266/tests/kraken/driver.html?testName=ai-astar) 19:22:33 INFO - PROCESS | 28783 | RSS: Main: 264654848 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | __start_tp_report 19:22:33 INFO - PROCESS | 28783 | _x_x_mozilla_page_load 19:22:33 INFO - PROCESS | 28783 | _x_x_mozilla_page_load_details 19:22:33 INFO - PROCESS | 28783 | |i|pagename|runs| 19:22:33 INFO - PROCESS | 28783 | |0;ai-astar;112;106;113;113;111;112;112;113;111;111 19:22:33 INFO - PROCESS | 28783 | |1;audio-beat-detection;152;124;161;181;125;152;156;137;187;124 19:22:33 INFO - PROCESS | 28783 | |2;audio-dft;160;160;160;160;161;161;162;161;160;161 19:22:33 INFO - PROCESS | 28783 | |3;audio-fft;75;75;75;129;75;85;76;75;137;75 19:22:33 INFO - PROCESS | 28783 | |4;audio-oscillator;92;91;116;91;91;92;122;91;92;121 19:22:33 INFO - PROCESS | 28783 | |5;imaging-gaussian-blur;117;117;116;116;116;116;116;116;116;116 19:22:33 INFO - PROCESS | 28783 | |6;imaging-darkroom;145;146;145;145;146;145;145;145;145;145 19:22:33 INFO - PROCESS | 28783 | |7;imaging-desaturate;94;95;95;95;95;94;95;94;95;96 19:22:33 INFO - PROCESS | 28783 | |8;json-parse-financial;56;57;56;55;57;58;56;75;55;53 19:22:33 INFO - PROCESS | 28783 | |9;json-stringify-tinderbox;58;58;58;58;59;59;59;59;60;59 19:22:33 INFO - PROCESS | 28783 | |10;stanford-crypto-aes;78;77;83;81;79;85;81;80;79;81 19:22:33 INFO - PROCESS | 28783 | |11;stanford-crypto-ccm;124;128;130;122;122;126;122;134;155;124 19:22:33 INFO - PROCESS | 28783 | |12;stanford-crypto-pbkdf2;168;165;168;169;169;164;172;171;164;168 19:22:33 INFO - PROCESS | 28783 | |13;stanford-crypto-sha256-iterative;70;68;67;65;64;64;63;63;62;65 19:22:33 INFO - PROCESS | 28783 | __end_tp_report 19:22:33 INFO - PROCESS | 28783 | __start_cc_report 19:22:33 INFO - PROCESS | 28783 | _x_x_mozilla_cycle_collect,1610 19:22:33 INFO - PROCESS | 28783 | __end_cc_report 19:22:33 INFO - PROCESS | 28783 | __startTimestamp1462587753116__endTimestamp 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | ------- Summary: start ------- 19:22:33 INFO - PROCESS | 28783 | Number of tests: 14 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#0] ai-astar Cycles:10 Average:111.40 Median:112.00 stddev:2.07 (1.8%) stddev-sans-first:2.18 19:22:33 INFO - PROCESS | 28783 | Values: 112.0 106.0 113.0 113.0 111.0 112.0 112.0 113.0 111.0 111.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#1] audio-beat-detection Cycles:10 Average:149.90 Median:154.00 stddev:22.71 (14.7%) stddev-sans-first:24.07 19:22:33 INFO - PROCESS | 28783 | Values: 152.0 124.0 161.0 181.0 125.0 152.0 156.0 137.0 187.0 124.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#2] audio-dft Cycles:10 Average:160.60 Median:161.00 stddev:0.70 (0.4%) stddev-sans-first:0.71 19:22:33 INFO - PROCESS | 28783 | Values: 160.0 160.0 160.0 160.0 161.0 161.0 162.0 161.0 160.0 161.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#3] audio-fft Cycles:10 Average:87.70 Median:75.00 stddev:24.15 (32.2%) stddev-sans-first:25.17 19:22:33 INFO - PROCESS | 28783 | Values: 75.0 75.0 75.0 129.0 75.0 85.0 76.0 75.0 137.0 75.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#4] audio-oscillator Cycles:10 Average:99.90 Median:91.00 stddev:13.73 (15.1%) stddev-sans-first:14.26 19:22:33 INFO - PROCESS | 28783 | Values: 92.0 91.0 116.0 91.0 91.0 92.0 122.0 91.0 92.0 121.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#5] imaging-gaussian-blur Cycles:10 Average:116.20 Median:116.00 stddev:0.42 (0.4%) stddev-sans-first:0.33 19:22:33 INFO - PROCESS | 28783 | Values: 117.0 117.0 116.0 116.0 116.0 116.0 116.0 116.0 116.0 116.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#6] imaging-darkroom Cycles:10 Average:145.20 Median:145.00 stddev:0.42 (0.3%) stddev-sans-first:0.44 19:22:33 INFO - PROCESS | 28783 | Values: 145.0 146.0 145.0 145.0 146.0 145.0 145.0 145.0 145.0 145.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.60 19:22:33 INFO - PROCESS | 28783 | Values: 94.0 95.0 95.0 95.0 95.0 94.0 95.0 94.0 95.0 96.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#8] json-parse-financial Cycles:10 Average:57.80 Median:56.50 stddev:6.20 (11.0%) stddev-sans-first:6.54 19:22:33 INFO - PROCESS | 28783 | Values: 56.0 57.0 56.0 55.0 57.0 58.0 56.0 75.0 55.0 53.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#9] json-stringify-tinderbox Cycles:10 Average:58.70 Median:59.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 19:22:33 INFO - PROCESS | 28783 | Values: 58.0 58.0 58.0 58.0 59.0 59.0 59.0 59.0 60.0 59.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#10] stanford-crypto-aes Cycles:10 Average:80.40 Median:81.00 stddev:2.37 (2.9%) stddev-sans-first:2.35 19:22:33 INFO - PROCESS | 28783 | Values: 78.0 77.0 83.0 81.0 79.0 85.0 81.0 80.0 79.0 81.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#11] stanford-crypto-ccm Cycles:10 Average:128.70 Median:127.00 stddev:10.04 (7.9%) stddev-sans-first:10.51 19:22:33 INFO - PROCESS | 28783 | Values: 124.0 128.0 130.0 122.0 122.0 126.0 122.0 134.0 155.0 124.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.50 stddev:2.74 (1.6%) stddev-sans-first:2.91 19:22:33 INFO - PROCESS | 28783 | Values: 168.0 165.0 168.0 169.0 169.0 164.0 172.0 171.0 164.0 168.0 19:22:33 INFO - PROCESS | 28783 | 19:22:33 INFO - PROCESS | 28783 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.10 Median:65.00 stddev:2.51 (3.9%) stddev-sans-first:1.94 19:22:33 INFO - PROCESS | 28783 | Values: 70.0 68.0 67.0 65.0 64.0 64.0 63.0 63.0 62.0 65.0 19:22:33 INFO - PROCESS | 28783 | -------- Summary: end -------- 19:22:33 INFO - PROCESS | 28783 | 19:22:34 INFO - TEST-INFO | 28783: exit 0 19:22:34 INFO - TEST-OK | kraken | took 61437ms 19:22:34 INFO - SUITE-END | took 1175s 19:22:34 INFO - Completed test suite (00:19:35) 19:22:34 INFO - javascript benchmark 19:22:34 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3128.3899639570277, 3149.6613939075887, 3175.3173067790426, 3177.4916013437855, 3217.8551965161564, 5270.499302566255, 5300.125479295912, 5352.598555108363, 5370.0, 5399.5680345572355, 5316.0, 5316.3354580677415, 5337.492938170877, 5392.865178370543, 5405.243265942771, 4772.308015337782, 4872.488388719186, 4878.390201224847, 4881.975590122044, 4972.105021096201, 4899.216125419934, 4917.06575750608, 4927.211646136619, 4955.504449555052, 4996.275540046691, 4724.24412094065, 4784.665073444843, 4813.903721925577, 4841.8547443576745, 4848.393950756155, 4857.951420485785, 4863.805447782084, 4864.440589332236, 4900.975495122537, 4927.43334516528, 4816.205326121186, 4829.1548978928895, 4830.73430961297, 4835.202191638376, 4882.292067650206, 5165.328507294065, 5175.301334319856, 5184.948150518522, 5209.531142197183, 5240.056789777838, 5664.2636457260705, 5688.971555142198, 5712.828615141552, 5721.71391430431, 5728.283964504437, 5713.5429165667065, 5759.424057594249, 5764.682942438164, 5777.24895763548, 5781.768729250825, 5232.790688372461, 5257.894842103175, 5264.447233040552, 5304.336957880227, 5337.492938170868, 5698.943010569924, 5763.567732420085, 5769.711514424346, 5770.105633626795, 5771.797987070432, 5428.7285635718845, 5523.6409633373705, 5563.471470210324, 5569.944300556943, 5576.804811831566, 5577.330720313588, 5640.238567793295, 5641.097424412074, 5644.6613203207935, 5664.773409063674, 5637.633553818989, 5644.040513112781, 5648.6610803351105, 5649.519790817744, 5654.830355089354, 5409.594280428983, 5571.860703482364, 5633.155026745939, 5636.18275350072, 5701.230333904953, 4359.433273674402, 4370.847020354336, 4387.583179597933, 4392.494863090722, 4430.734155950653, 3913.099987003005, 3923.43110249018, 3926.391409331558, 3929.1159489115294, 3959.7822119783114, 5201.0, 5247.527722504993, 5302.787888484495, 5442.292501974718, 5467.097928841697, 5688.175214593944, 5710.628809127394, 5727.4845263926445, 5731.512821410143, 5764.423557644202, 5345.438728933484, 5352.571794256528, 5357.678539287655, 5366.436524164907, 5368.5436737876935, 4778.402699662543, 4833.806647734122, 4861.052094841542, 4915.48387419323, 4953.479884612136, 5876.294844618587, 5877.588568800228, 5878.794242201502, 5880.441358070976, 5929.229200203946, 5664.830055098354, 5685.147227915846, 5689.487946084872, 5696.572757043155, 5697.088465845445, 5750.338711048115, 5830.679312637845, 5866.794662186803, 5902.439268269508, 5920.171176035273, 5681.602287839812, 5684.8294551163535, 5689.658620482619, 5690.174924635822, 5694.515966142924, 5708.686022268815, 5731.799387021434, 5755.050416681201, 5764.13537969308, 5778.046622307273, 5383.919241211307, 5510.504054635102, 5512.5589952804485, 5564.248826408545, 5572.972135139298, 5573.164025396223, 5577.191307260506, 5580.190872323409, 5586.273784408001, 5590.832275031756, 5308.522232998894, 5481.0, 5485.9725701371235, 5492.368377636623, 5540.861478463071, 5545.195946587642, 5612.803551875665, 5614.2701448811395, 5672.177534257427, 5682.488576028178, 5512.366077900932, 5512.614117011771, 5520.06158952987, 5525.033119204204, 5567.749451274622], "value": 5249.424814813549, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [638.2373064188293, 649.4641920415659, 650.9153810004699, 658.1937127019402, 662.7017841971115, 42951.35572966404, 44785.0, 44863.551364486346, 48751.26873096907, 48788.0, 47626.52373476264, 48153.0, 48211.758941205335, 48295.55113346597, 48343.75828120863, 8977.371583989123, 9158.404703694272, 9176.770580735469, 9179.678711245107, 9182.908170918272, 8989.235914947238, 9032.232260257886, 9071.909280907206, 9075.0, 9077.546122693871, 9071.909280907206, 9111.72664820053, 9117.95441022794, 9118.133777291147, 9121.224695900857, 9081.18269355758, 9111.954440227822, 9114.908850911472, 9115.179633832953, 9117.954410227972, 8920.643174273086, 8947.50788706621, 9001.414908031022, 9005.864912026325, 9008.864867027, 41397.0, 41523.7923810379, 41690.79154604208, 41864.0, 41871.37192942108, 3474.9131271718156, 3759.4924685167425, 3963.326234540135, 4161.146964872194, 4219.535851056381, 31804.0, 31931.201719956956, 31975.0, 32347.676523234702, 32425.351492970007, 4075.286824805647, 4091.468109145822, 4195.811188496495, 4198.097409057067, 4248.214080395137, 314792.0, 318146.9999999977, 318339.0, 318474.0, 321196.9999999977, 62885.37114628795, 63494.36505634977, 63726.681366593795, 63999.0, 64000.67999659972, 58264.70867645634, 64136.0, 66847.33152668411, 66872.33127668759, 66905.6654716733, 16425.09661968579, 17166.570835729206, 17174.0, 17222.741658875126, 17425.39011134604, 13382.197068175747, 13518.662033449242, 13525.053246272862, 13531.39108740109, 13536.729265414637, 17958.461246162635, 18292.817071829377, 19589.902050489658, 19751.0, 19806.90096549508, 7147.785566433015, 7238.420926325882, 7241.5293005954445, 7260.41916646667, 7448.255174482617, 990.1979396688705, 1013.1793247469574, 1108.8780234174235, 1191.141818762733, 1192.8747481514315, 29642.85178574093, 32824.34351313008, 32977.83511082477, 33124.834375828446, 33453.0, 1134.982975255372, 1216.7444836584239, 1224.088054399478, 1248.825164476974, 1320.8695391921208, 17435.389761358547, 17533.736993945102, 17653.470395888144, 17656.117194140497, 17720.55698607545, 496459.0, 497218.0, 498061.0, 500920.0, 501755.0, 41109.58890411058, 41375.379369309485, 41697.37453938133, 42022.36966445506, 42040.78979605083, 1404.1434724817852, 1410.7672234081263, 1443.7112577484545, 1453.876420504269, 1465.4724299252473, 30726.38547228998, 31325.0, 31384.843075784476, 31616.683833161373, 32651.510227346134, 51048.744756275984, 52024.0, 52044.0, 52075.2188717162, 52093.47906520886, 439244.0, 447302.0, 447904.0, 448275.0, 448560.0, 26135.215943521725, 26879.731202687723, 26938.057167999024, 29285.267868303465, 29763.553546696385, 42369.36445953375, 50126.49873501218, 55030.0, 55129.72435137798, 56026.43973560212, 42177.36733948932, 43610.563894360654, 43655.126897462505, 43705.0, 44221.0, 42447.5755242456, 42546.36180457358, 42834.571654283056, 43022.35466468068, 43075.35386969135, 42501.0, 43839.78080109579, 44022.77988610036, 44491.332630011224, 44514.55485445104, 36014.639853601126, 37253.813730932256, 37553.0, 37779.81110094432, 38864.0, 28788.85605571959, 28876.566851496824, 28897.711022890344, 29036.854815725786, 29253.268668283465], "value": 20522.854750543975, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [387.9379299312107, 397.9422983667366, 414.0848724319255, 414.94190813286144, 431.8812326610182, 12027.819582706266, 15248.161351125684, 18870.811291887094, 18940.147693353814, 18960.146793394295, 19537.70693439596, 20012.0994555245, 20455.795442045575, 20733.274335398262, 20767.376978690627, 6412.839679008015, 6480.902786458207, 6607.240167380741, 6621.139251897247, 6626.337366263383, 6262.624242545439, 6438.903416448734, 6443.452306553949, 6446.323136070715, 6485.772997945073, 6388.169537960059, 6393.264774550939, 6495.805125846208, 6497.05792660064, 6574.737010519598, 6425.903611445832, 6524.967375163117, 6533.118029066087, 6547.377999090103, 6616.900746488806, 6171.814845554595, 6360.586561873463, 6384.074309225182, 6405.327440618761, 6410.8717825643225, 24715.0, 24867.253982380556, 25938.0, 26051.478970420674, 26052.218433447026, 2412.155745489082, 2524.0913271222344, 2626.6060090986316, 3110.8289044102567, 3389.4237979543536, 15389.153596552185, 15455.690886182214, 15655.686886262325, 16067.598310042225, 16164.75752863708, 2085.8957052147484, 2131.509752756859, 2133.189388032538, 2208.2491952736145, 2255.07541907817, 88664.55667721685, 92859.53570232174, 93256.53371733165, 93467.53266233693, 93812.53093734555, 17100.230489627997, 17563.20965556553, 17821.198046087957, 17841.821581784276, 18080.819191808176, 32000.199994999846, 32083.0, 32164.35671286561, 32344.0, 32381.409487241435, 9938.900610993798, 10167.4916254187, 10202.64290749835, 10240.743981400525, 10426.5829366826, 8480.830383392298, 8530.14698530142, 8563.272121869712, 8703.129687031373, 8715.564221788885, 8592.355573332025, 8772.473651580925, 8805.207531322212, 8855.114488551222, 8936.32977526688, 3209.326041531305, 3250.837458127084, 3262.2986057997646, 3401.8469168886963, 3520.982395088008, 907.2651152566442, 1007.0131271354114, 1023.2837014090167, 1040.921930855189, 1055.4089709762557, 18419.0, 18816.435506934813, 18911.3381031666, 18912.43262702121, 19210.327638532865, 1013.189448441244, 1039.734867608755, 1046.0480962324248, 1056.1920131099714, 1064.4252103863982, 10357.326773759682, 10567.524461399256, 10994.560217591366, 11261.549538018551, 11748.941255293668, 457070.0, 457613.0, 457837.0, 458141.0, 471967.0, 51958.22062669063, 62681.0, 90257.09742902618, 91688.54155729178, 91947.0, 1090.7818436312768, 1112.22700223344, 1159.5361855257963, 1161.4599211366756, 1177.0936378988301, 17369.13154342303, 17489.650206995535, 17681.115944202997, 17733.911330443265, 18016.189271483076, 38477.0, 38956.6104338953, 39498.41383851145, 40764.79617601893, 40875.591244087176, 51425.48574514357, 52526.737366312926, 58117.0, 58181.41818581929, 58317.70841145767, 7400.703971841066, 7798.532088074507, 7875.251851074138, 8663.26362259215, 9006.369554131148, 9756.560954757195, 11473.598424055119, 11885.524579016743, 12337.444814983168, 12443.875561244271, 10630.30902991303, 11018.338899666045, 11407.201495895208, 11645.59240426581, 11901.345426001306, 11619.883801161881, 11705.122115841175, 11818.645440636794, 11832.70418239551, 11861.940690296493, 11617.244879083171, 11820.468078936297, 11990.280583165038, 12024.819627705767, 12120.969717574106, 7956.5226086435, 7995.320397766255, 8012.399070069651, 8013.118556958846, 8066.23370779775, 8874.068222836768, 10835.67492975212, 11275.718107047061, 11421.0, 11659.76680466369], "value": 10240.196215009139, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1284.1332100831949, 1508.502194275889, 1511.2821409830328, 1681.3862940026886, 1841.806610305918, 8063.677452901877, 8461.619227134779, 8488.61801218946, 8647.351448641346, 8712.128787121284, 8433.198846109624, 8603.91396086039, 8611.56942152893, 8612.052674205832, 8637.39538232324, 2043.3359158273547, 2260.660900864867, 2285.462916214688, 2320.709911261092, 2329.231353653299, 2340.9531809363802, 2346.8474549154334, 2351.3886389538757, 2353.0234952494693, 2353.0705371378294, 2227.3318004598636, 2287.7254729432493, 2291.633338665814, 2292.025888997178, 2297.1959814065026, 1981.8513611479125, 2241.7197850268635, 2306.112146823485, 2308.284431826139, 2318.3392733070996, 2271.1029143488395, 2275.9089636414687, 2278.6809846621486, 2279.7606251343536, 2321.5124823787023, 8655.350848686374, 8660.350473714434, 8677.088905664878, 8684.305255579538, 8724.38929274951, 5125.538701516844, 5286.15421532553, 5291.3385826771655, 5325.0, 5328.6802791832615, 3273.2307907641684, 3370.7808992415416, 3374.5275661407422, 3399.0312760863035, 3437.9312413751586, 1139.4587570903836, 1158.3629004047825, 1160.8955194032494, 1174.653477224221, 1186.1933884958225, 12063.879361206451, 12135.150539462153, 12137.635870923887, 12138.514459421702, 12142.271463712206, 4646.442426908792, 4763.214069678534, 4809.831655892027, 4816.831410900601, 4852.902941941142, 10163.898361016296, 10171.084602385672, 10171.186305095574, 10175.44035078063, 10196.0, 4749.596284315872, 5312.2297266896085, 5324.520793128637, 5336.67979921206, 5342.2253773202665, 4609.308603709471, 4741.193997020515, 4754.025424787979, 4784.378030855966, 4829.0, 3928.175083232496, 3950.9012274692786, 3979.3036218662196, 4024.7786371749835, 4047.5547689754103, 1273.9490420383265, 1300.245857402723, 1307.9346032698327, 1319.6502926724427, 1327.462377737018, 969.9078587534173, 985.8126955878361, 1002.4135880509838, 1005.7222125888763, 1006.8137394582026, 5767.913481297784, 6054.666993315322, 6092.055731361646, 6134.693265336716, 6290.433860952537, 906.6735975048977, 917.7980844214262, 954.174638937328, 1011.1051719228535, 1011.8077565262577, 3784.7539909905768, 3853.3641949078087, 3920.3923391874305, 3926.646601805794, 3937.684985201177, 10167.44079075658, 10230.693079207636, 10436.478176091165, 10476.580936762597, 10486.947565262124, 10165.085142337226, 10363.63727269542, 10368.637097701545, 10389.272750907516, 10659.147268218523, 7998.680052797823, 8075.676972921017, 8133.959330203429, 8191.0580283267, 8257.174282571816, 6005.669688167018, 6166.691665416801, 6248.0, 6292.433680968735, 6316.62100273985, 9605.903940960781, 9995.999999999709, 10165.898341016495, 10214.795704086026, 10254.743631409274, 9939.254555908481, 10234.488275586042, 10320.896791031993, 10335.431551264752, 10343.431111288952, 6985.126859142607, 7554.0, 7561.357284630868, 7676.731314403969, 7716.8456630868195, 8531.061583225965, 8534.359923005675, 8543.273821724973, 8581.313494920516, 8605.053444121268, 7503.737369192052, 7663.195364486872, 7667.463277570517, 7683.039620047494, 7776.261255180749, 7381.520201186906, 7442.7395041173295, 7475.626218688935, 7554.848903022019, 7563.659635316314, 7001.334873187039, 7025.754098606524, 7085.751998680022, 7091.680874360563, 7108.573485590881, 4838.854834354975, 5123.129068058513, 5225.0, 5230.63385563007, 5294.867628309323, 5270.262163297218, 5355.277037599874, 5436.483534064258, 5443.510084092292, 5446.700431476307], "value": 4622.712851827773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [339.4890689512284, 339.8674516938394, 346.77113105350463, 350.4550424090538, 351.5991769382904, 5169.172932330828, 5180.300659410983, 5182.4299327073995, 5200.671570195078, 5314.548263397616, 5184.248283998818, 5190.662606930557, 5204.906969536401, 5228.241904923784, 5235.424103348629, 3913.236918800842, 3937.310970580137, 4096.754194748325, 4108.506979162505, 4140.37894315852, 4079.0822065035277, 4087.366458198969, 4108.8356465741335, 4122.649574786146, 4151.211269858717, 3883.9805800970958, 4045.797710114497, 4077.408775727518, 4078.2659121358142, 4082.4080508326274, 3818.6443812446737, 3997.34043882759, 4008.398740188966, 4070.5725898780793, 4091.729090354852, 3615.9096022599383, 3625.0, 3645.398509245971, 3651.2514934438377, 3652.7032903319205, 4964.230544265644, 4979.701217926936, 4997.001798920654, 5019.42276638184, 5021.121303771825, 4525.479569849444, 4598.402207713009, 4651.232546629836, 4672.252439609646, 4686.27362758773, 5395.70323632204, 5405.135178371442, 5412.431694672042, 5429.755660995264, 5433.592480563973, 4001.2597669431248, 4008.83964641414, 4013.819378127991, 4018.4374187613757, 4040.454538637305, 5343.572514198855, 5636.2954630671165, 5658.490735833753, 5672.886542269172, 5696.287964004458, 3982.9601703983167, 3988.3618621020496, 3998.0, 4002.6197511236387, 4027.0, 4062.1469491406547, 4063.146739184745, 4065.6137666921595, 4070.5725898780793, 4094.754314741125, 4455.4876189238, 4642.9535704643195, 4650.720956742606, 4675.602573781267, 4688.8124475021295, 5496.087073850866, 5508.862278443071, 5521.171824226398, 5542.806001789918, 5546.500814926676, 1347.47448495087, 1365.0581099041628, 1369.5275130080106, 1370.7464119137992, 1376.7040086381276, 909.4634165842185, 912.4160537256162, 944.6740874398322, 952.1668540027476, 956.8995255498212, 5159.277701121846, 5278.630495865253, 5282.656627319212, 5295.4969277918535, 5317.787288508416, 5487.847552014122, 5532.834014979477, 5554.72226388679, 5635.182898479779, 5674.4892959633835, 4959.404871415453, 5281.788728450896, 5331.4935081168005, 5343.812966546152, 5366.060939335601, 2871.3826527296733, 2884.77397106229, 2893.9276518086795, 2908.8109272897195, 2954.9113526594238, 5706.229658995982, 5724.255846739898, 5736.971315143398, 5752.0, 5762.683052432157, 5537.889242215214, 5579.7489112990015, 5626.493615574536, 5629.748100759729, 5652.236948011966, 5576.498115169573, 5587.804426845041, 5621.409751975983, 5622.453825198021, 5673.858153546194, 5264.131233412237, 5477.616566840283, 5480.287562616834, 5483.479069488392, 5538.030844602259, 5622.437756224344, 5647.774089036392, 5650.830475085754, 5650.915236271377, 5651.378348381757, 5806.390329015561, 5818.301803783573, 5859.94140058594, 5893.233879595625, 5901.557383196363, 3838.466612036043, 4053.2096241232675, 4058.776767277828, 4128.4632997710105, 4137.97931010343, 5595.440455954372, 5601.467860553241, 5604.159376093618, 5660.377358490645, 5665.433456654301, 5441.911617676401, 5445.720255739977, 5447.264619276347, 5483.917741233805, 5508.889822203613, 5208.296879921162, 5420.349558053059, 5420.75606597712, 5424.349078110652, 5448.782048718007, 5163.561097306771, 5346.411894691503, 5353.544948679406, 5372.086745253395, 5385.868967516865, 5340.866478338073, 5455.263539422127, 5457.754401052041, 5470.261514695465, 5472.781088756406, 5390.218418329242, 5409.161579955114, 5411.83764487066, 5417.377001644862, 5438.960389853804], "value": 4169.052366057896, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [764.5718397697293, 772.6600295869816, 841.600239886054, 864.1833467373333, 872.9781755456113, 12588.244705317677, 13241.999999999987, 13299.601011969633, 13315.0, 14788.704225915475, 12765.808512872314, 12988.54540091097, 13016.60950171494, 13025.0, 13038.869611303884, 690.5925503952667, 805.5005896344275, 821.5686764448652, 824.1099612418603, 832.1927730101822, 847.8516259654566, 848.6690190825583, 850.4429598612918, 851.2338894994492, 851.3019324154196, 880.5685214245005, 881.422668152358, 881.4226681523612, 881.5460038080362, 881.6076845803588, 716.931891470312, 864.0365991918997, 887.6981826179066, 887.8579427291667, 891.1489527501285, 647.3138473218356, 649.0102593544834, 649.3668673043792, 649.3863299182275, 649.5226008883515, 12981.156224845356, 12990.610281691565, 12991.870081299161, 13018.088733788638, 13038.608841734764, 13687.452501899912, 13972.650683732887, 14066.71866562663, 14076.92961535196, 14197.432102715879, 11427.200095993283, 11699.18105732599, 11782.587609433629, 12018.098642601839, 12022.341700694842, 4467.195904737146, 4480.6191473724775, 4495.5953964143055, 4546.454425468932, 4653.976730116327, 56175.0, 56197.71901140468, 57275.42724572784, 57903.13145302824, 58169.41830581682, 10510.632127875488, 10596.470176491144, 10869.184811139037, 10882.455877206108, 11099.112071034298, 19168.13743381551, 19316.227350906087, 19324.1304141314, 19335.226590936487, 19386.5153371164, 7890.8816367754525, 8035.437519373705, 8200.753977380688, 8359.916400835913, 8397.874031889527, 4599.655025873073, 4610.9308360374625, 4614.861554153381, 4639.884002899955, 4724.196886529298, 3273.85267662951, 3355.0605830367535, 3357.378884906251, 3358.294758100826, 3364.175776934667, 584.6959581017846, 667.7395815631908, 689.2039694153261, 695.5583204664973, 703.5673061067407, 581.6946103295803, 642.3768944124192, 676.7428377216725, 725.0719079578148, 728.7121586973116, 9381.624735010522, 9749.415035097916, 9765.121139097373, 9777.266704997153, 9872.506374681381, 625.3590070178104, 690.602903330587, 746.932776050158, 756.8675481790665, 781.160252728308, 4523.212793889504, 4644.233701439292, 4673.112108699336, 4709.67032307742, 4740.597049250852, 44530.777346113064, 44806.10387792291, 54512.0, 56963.14555281674, 67847.0, 56009.0, 57176.400358172985, 57399.71300143473, 57710.0, 58034.419655803744, 52730.20904686433, 52996.47003529993, 53058.46941530613, 53350.4664953361, 53448.73275633597, 9561.187299079656, 9596.23230141601, 9721.659741909, 9764.0, 9769.511524423893, 33150.83424582861, 33887.491687625195, 34312.828435857664, 34838.30323393569, 35569.1107722309, 45575.088498230514, 46460.53539464562, 46635.53364466448, 46638.0, 46856.297155543376, 12889.291088989821, 12939.094263401472, 13017.739645207232, 13069.673258168621, 13139.54011609589, 13597.796033059314, 13743.587692369245, 14178.716425671222, 14441.927790360982, 14443.78334324965, 13338.799918001043, 13510.527131550349, 13676.931615342257, 13860.514881979083, 14180.787288190892, 13113.541026064042, 13248.337583120998, 13614.523491677744, 13917.373718182504, 14046.78929816033, 13253.602391928258, 13266.204027758366, 13406.128601640861, 13998.440062397798, 14104.929475352557, 10122.443265620166, 10688.358698478116, 10724.0, 10988.780224395627, 11245.212835101463, 11216.439178041228, 11221.0, 11305.378204198849, 11319.320840749582, 11505.367204803817], "value": 6883.899339842744, "unit": "score"}], "name": "dromaeo_css", "value": 7259.597086230167, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 106.0, 113.0, 113.0, 111.0, 112.0, 112.0, 113.0, 111.0, 111.0], "value": 111.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 124.0, 161.0, 181.0, 125.0, 152.0, 156.0, 137.0, 187.0, 124.0], "value": 149.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 160.0, 160.0, 160.0, 161.0, 161.0, 162.0, 161.0, 160.0, 161.0], "value": 160.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 75.0, 75.0, 129.0, 75.0, 85.0, 76.0, 75.0, 137.0, 75.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 116.0, 91.0, 91.0, 92.0, 122.0, 91.0, 92.0, 121.0], "value": 99.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0, 116.0], "value": 116.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 145.0, 145.0, 146.0, 145.0, 145.0, 145.0, 145.0, 145.0], "value": 145.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 95.0, 95.0, 94.0, 95.0, 94.0, 95.0, 96.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 56.0, 55.0, 57.0, 58.0, 56.0, 75.0, 55.0, 53.0], "value": 57.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 58.0, 59.0, 59.0, 59.0, 59.0, 60.0, 59.0], "value": 58.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 77.0, 83.0, 81.0, 79.0, 85.0, 81.0, 80.0, 79.0, 81.0], "value": 80.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [124.0, 128.0, 130.0, 122.0, 122.0, 126.0, 122.0, 134.0, 155.0, 124.0], "value": 128.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 168.0, 169.0, 169.0, 164.0, 172.0, 171.0, 164.0, 168.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 67.0, 65.0, 64.0, 64.0, 63.0, 63.0, 62.0, 65.0], "value": 65.1, "unit": "score"}], "name": "kraken", "value": 1524.2, "alertThreshold": 2.0}]} 19:22:34 INFO - Return code: 0 19:22:34 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 19:22:34 ERROR - # TBPL SUCCESS # 19:22:34 INFO - Running post-action listener: _resource_record_post_action 19:22:34 INFO - ##### 19:22:34 INFO - ##### Finished run-tests step (success) 19:22:34 INFO - ##### 19:22:34 INFO - Running post-run listener: _resource_record_post_run 19:22:35 INFO - Total resource usage - Wall time: 1193s; CPU: 13.0%; Read bytes: 4096; Write bytes: 544202752; Read time: 16; Write time: 618412 19:22:35 INFO - install - Wall time: 17s; CPU: 14.0%; Read bytes: 0; Write bytes: 254730240; Read time: 0; Write time: 317952 19:22:35 INFO - run-tests - Wall time: 1177s; CPU: 13.0%; Read bytes: 4096; Write bytes: 288133120; Read time: 16; Write time: 300444 19:22:35 INFO - Running post-run listener: _upload_blobber_files 19:22:35 INFO - Blob upload gear active. 19:22:35 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:22:35 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 19:22:35 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'] 19:22:35 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 19:22:35 INFO - (blobuploader) - INFO - Open directory for files ... 19:22:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:22:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:22:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:36 INFO - (blobuploader) - INFO - Done attempting. 19:22:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:22:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:22:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:22:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:22:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:22:37 INFO - (blobuploader) - INFO - Done attempting. 19:22:37 INFO - (blobuploader) - INFO - Iteration through files over. 19:22:37 INFO - Return code: 0 19:22:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:22:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:22:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3e0be25d1f178b6b683b2360cae790b44dd1cb450782771b90b9dfdfdf4739d0a4f65fb2b99335ec1088a130be02309ea8713c99845b6bd0042989afc4ca9784", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87a5b733eec82e4b2251c468a983c769e01671ba6b1a4bfff84bdf164e3f10b75deca400228333af9c3b36ec2225929cf0b75a01081b726d40fde6abe4b059cf"} 19:22:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:22:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:22:37 INFO - Contents: 19:22:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/3e0be25d1f178b6b683b2360cae790b44dd1cb450782771b90b9dfdfdf4739d0a4f65fb2b99335ec1088a130be02309ea8713c99845b6bd0042989afc4ca9784", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87a5b733eec82e4b2251c468a983c769e01671ba6b1a4bfff84bdf164e3f10b75deca400228333af9c3b36ec2225929cf0b75a01081b726d40fde6abe4b059cf"} 19:22:37 INFO - Running post-run listener: copy_logs_to_upload_dir 19:22:37 INFO - Copying logs to upload dir... 19:22:37 INFO - mkdir: /builds/slave/test/build/upload/logs 19:22:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1231.289008 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 31 secs) (at 2016-05-06 19:22:37.273532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 19:22:37.277100) ========= 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=7de08804244eb36560b44971000001c3-1462581191.140079-614059795 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/3e0be25d1f178b6b683b2360cae790b44dd1cb450782771b90b9dfdfdf4739d0a4f65fb2b99335ec1088a130be02309ea8713c99845b6bd0042989afc4ca9784", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87a5b733eec82e4b2251c468a983c769e01671ba6b1a4bfff84bdf164e3f10b75deca400228333af9c3b36ec2225929cf0b75a01081b726d40fde6abe4b059cf"} build_url:https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/artifacts/public/build/firefox-49.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011364 build_url: 'https://queue.taskcluster.net/v1/task/TZd_gWkWQ9iJtvn9OxP6EQ/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/3e0be25d1f178b6b683b2360cae790b44dd1cb450782771b90b9dfdfdf4739d0a4f65fb2b99335ec1088a130be02309ea8713c99845b6bd0042989afc4ca9784", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Inbound/sha512/87a5b733eec82e4b2251c468a983c769e01671ba6b1a4bfff84bdf164e3f10b75deca400228333af9c3b36ec2225929cf0b75a01081b726d40fde6abe4b059cf"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 19:22:37.302248) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:22:37.302612) ========= 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=7de08804244eb36560b44971000001c3-1462581191.140079-614059795 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004379 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 19:22:37.386916) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 19:22:37.387262) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 19:22:37.387667) ========= ========= Total master_lag: 0.19 =========