builder: mozilla-central_ubuntu64_hw_test-dromaeojs slave: talos-linux64-ix-041 starttime: 1461042617.32 results: success (0) buildid: 20160418205131 builduid: d5db07eff05d47449dc0edb10d63b704 revision: cb65ec85049efa44b18e2e8d06af26768c3bfb94 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.321791) ========= master: http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.322358) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.322644) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.339480) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.339848) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2016-04-18 22:10:18-- 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% 70.9M=0s 2016-04-18 22:10:18 (70.9 MB/s) - `archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.146891 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.500598) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.500886) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.017276 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.586862) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:17.587238) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev cb65ec85049efa44b18e2e8d06af26768c3bfb94 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev cb65ec85049efa44b18e2e8d06af26768c3bfb94 --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=8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2016-04-18 22:10:18,638 truncating revision to first 12 chars 2016-04-18 22:10:18,638 Setting DEBUG logging. 2016-04-18 22:10:18,638 attempt 1/10 2016-04-18 22:10:18,638 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/cb65ec85049e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-18 22:10:18,888 unpacking tar archive at: mozilla-central-cb65ec85049e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.523507 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:18.123774) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:18.124169) ========= script_repo_revision: cb65ec85049efa44b18e2e8d06af26768c3bfb94 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:18.124623) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:18.124922) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-18 22:10:18.250615) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-04-18 22:10:18.251041) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-Non-PGO'] 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=8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 22:10:19 INFO - MultiFileLogger online at 20160418 22:10:19 in /builds/slave/test 22:10:19 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 22:10:19 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:10:19 INFO - {'append_to_log': False, 22:10:19 INFO - 'base_work_dir': '/builds/slave/test', 22:10:19 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 22:10:19 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:10:19 INFO - 'branch': 'Firefox-Non-PGO', 22:10:19 INFO - 'buildbot_json_path': 'buildprops.json', 22:10:19 INFO - 'config_files': ('talos/linux_config.py',), 22:10:19 INFO - 'default_actions': ('clobber', 22:10:19 INFO - 'read-buildbot-config', 22:10:19 INFO - 'download-and-extract', 22:10:19 INFO - 'populate-webroot', 22:10:19 INFO - 'create-virtualenv', 22:10:19 INFO - 'install', 22:10:19 INFO - 'run-tests'), 22:10:19 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:10:19 INFO - 'download_minidump_stackwalk': True, 22:10:19 INFO - 'download_symbols': 'ondemand', 22:10:19 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:10:19 INFO - 'tooltool.py': '/tools/tooltool.py', 22:10:19 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:10:19 INFO - '/tools/misc-python/virtualenv.py')}, 22:10:19 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:10:19 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:10:19 INFO - 'installer_path': 'installer.exe', 22:10:19 INFO - 'log_level': 'info', 22:10:19 INFO - 'log_name': 'talos', 22:10:19 INFO - 'log_to_console': True, 22:10:19 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 22:10:19 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 22:10:19 INFO - 'opt_config_files': (), 22:10:19 INFO - 'pip_index': False, 22:10:19 INFO - 'sps_profile': False, 22:10:19 INFO - 'sps_profile_interval': 0, 22:10:19 INFO - 'suite': 'dromaeojs', 22:10:19 INFO - 'system_bits': '32', 22:10:19 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:10:19 INFO - 'title': 'talos-linux64-ix-041', 22:10:19 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:10:19 INFO - 'use_talos_json': True, 22:10:19 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:10:19 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:10:19 INFO - 'work_dir': 'build'} 22:10:19 INFO - ##### 22:10:19 INFO - ##### Running clobber step. 22:10:19 INFO - ##### 22:10:19 INFO - Running pre-action listener: _resource_record_pre_action 22:10:19 INFO - Running main action method: clobber 22:10:19 INFO - rmtree: /builds/slave/test/build 22:10:19 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:10:19 INFO - Running post-action listener: _resource_record_post_action 22:10:19 INFO - ##### 22:10:19 INFO - ##### Running read-buildbot-config step. 22:10:19 INFO - ##### 22:10:19 INFO - Running pre-action listener: _resource_record_pre_action 22:10:19 INFO - Running main action method: read_buildbot_config 22:10:19 INFO - Using buildbot properties: 22:10:19 INFO - { 22:10:19 INFO - "project": "", 22:10:19 INFO - "product": "firefox", 22:10:19 INFO - "script_repo_revision": "production", 22:10:19 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 22:10:19 INFO - "repository": "", 22:10:19 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs", 22:10:19 INFO - "buildid": "20160418205131", 22:10:19 INFO - "pgo_build": "False", 22:10:19 INFO - "basedir": "/builds/slave/test", 22:10:19 INFO - "buildnumber": 477, 22:10:19 INFO - "slavename": "talos-linux64-ix-041", 22:10:19 INFO - "master": "http://buildbot-master105.bb.releng.scl3.mozilla.com:8201/", 22:10:19 INFO - "platform": "ubuntu64_hw", 22:10:19 INFO - "scheduler": "tests-mozilla-central-linux64-talos", 22:10:19 INFO - "branch": "mozilla-central", 22:10:19 INFO - "repo_path": "mozilla-central", 22:10:19 INFO - "revision": "cb65ec85049efa44b18e2e8d06af26768c3bfb94", 22:10:19 INFO - "stage_platform": "linux64", 22:10:19 INFO - "builduid": "d5db07eff05d47449dc0edb10d63b704", 22:10:19 INFO - "slavebuilddir": "test" 22:10:19 INFO - } 22:10:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2. 22:10:19 INFO - Running post-action listener: _resource_record_post_action 22:10:19 INFO - ##### 22:10:19 INFO - ##### Running download-and-extract step. 22:10:19 INFO - ##### 22:10:19 INFO - Running pre-action listener: _resource_record_pre_action 22:10:19 INFO - Running main action method: download_and_extract 22:10:19 INFO - mkdir: /builds/slave/test/build/tests 22:10: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')]} 22:10:19 INFO - https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json matches https://queue.taskcluster.net 22:10:19 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:10:19 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:10:19 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:10:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json'}, attempt #1 22:10:26 INFO - Downloaded 1361 bytes. 22:10:26 INFO - Reading from file /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.test_packages.json 22:10:26 INFO - Using the following test package requirements: 22:10:26 INFO - {u'common': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:26 INFO - u'cppunittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.cppunittest.tests.zip'], 22:10:26 INFO - u'gtest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.gtest.tests.zip'], 22:10:26 INFO - u'jittest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'jsshell-linux-x86_64.zip'], 22:10:26 INFO - u'mochitest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.mochitest.tests.zip'], 22:10:26 INFO - u'mozbase': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'], 22:10:26 INFO - u'reftest': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.reftest.tests.zip'], 22:10:26 INFO - u'talos': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'], 22:10:26 INFO - u'web-platform': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.web-platform.tests.zip'], 22:10:26 INFO - u'xpcshell': [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 22:10:26 INFO - u'firefox-48.0a1.en-US.linux-x86_64.xpcshell.tests.zip']} 22:10:26 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'] for test suite category: common 22:10:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:26 INFO - https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:10:26 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:10:26 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:10:26 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:10:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:10:31 INFO - Downloaded 22209354 bytes. 22:10:31 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:31 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:10:32 INFO - Return code: 0 22:10:32 INFO - Downloading packages: [u'firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', u'firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'] for test suite category: talos 22:10:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:32 INFO - https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip matches https://queue.taskcluster.net 22:10:32 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:10:32 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:10:32 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip 22:10:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip'}, attempt #1 22:10:33 INFO - Downloaded 22209354 bytes. 22:10:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.common.tests.zip -d /builds/slave/test/build/tests 22:10:35 INFO - Return code: 0 22:10:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:35 INFO - https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip matches https://queue.taskcluster.net 22:10:35 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:35 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:35 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip 22:10:35 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip'}, attempt #1 22:10:36 INFO - Downloaded 10845952 bytes. 22:10:36 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 22:10:36 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.talos.tests.zip -d /builds/slave/test/build/tests 22:10:36 INFO - Return code: 0 22:10:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:36 INFO - https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 matches https://queue.taskcluster.net 22:10:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:10:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:10:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 to /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:10:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', 'file_name': '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2'}, attempt #1 22:10:37 INFO - Downloaded 54451310 bytes. 22:10:37 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:10:37 INFO - mkdir: /builds/slave/test/properties 22:10:37 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:10:37 INFO - Writing to file /builds/slave/test/properties/build_url 22:10:37 INFO - Contents: 22:10:37 INFO - build_url:https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 22:10:38 INFO - Running post-action listener: _resource_record_post_action 22:10:38 INFO - Running post-action listener: set_extra_try_arguments 22:10:38 INFO - ##### 22:10:38 INFO - ##### Running populate-webroot step. 22:10:38 INFO - ##### 22:10:38 INFO - Running pre-action listener: _resource_record_pre_action 22:10:38 INFO - Running main action method: populate_webroot 22:10:38 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:10:38 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:10:38 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:10:38 INFO - '--tppagecycles', 22:10:38 INFO - '7'], 22:10:38 INFO - 'tests': ['tsvgm']}}, 22:10:38 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:10:38 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:10:38 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:10:38 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:10:38 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:38 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:10:38 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['damp', 'tps']}, 22:10:38 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'tests': ['damp', 'tps']}, 22:10:38 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['dromaeo_dom']}, 22:10:38 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:10:38 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['a11yr', 22:10:38 INFO - 'ts_paint', 22:10:38 INFO - 'tpaint', 22:10:38 INFO - 'sessionrestore', 22:10:38 INFO - 'sessionrestore_no_auto_restore', 22:10:38 INFO - 'tabpaint']}, 22:10:38 INFO - 'other-e10s': {'tests': ['a11yr', 22:10:38 INFO - 'ts_paint', 22:10:38 INFO - 'tpaint', 22:10:38 INFO - 'sessionrestore', 22:10:38 INFO - 'sessionrestore_no_auto_restore', 22:10:38 INFO - 'tabpaint']}, 22:10:38 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:10:38 INFO - 'ts_paint', 22:10:38 INFO - 'tpaint', 22:10:38 INFO - 'sessionrestore', 22:10:38 INFO - 'sessionrestore_no_auto_restore', 22:10:38 INFO - 'tabpaint']}, 22:10:38 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:10:38 INFO - 'ts_paint', 22:10:38 INFO - 'tpaint', 22:10:38 INFO - 'sessionrestore', 22:10:38 INFO - 'sessionrestore_no_auto_restore', 22:10:38 INFO - 'tabpaint']}, 22:10:38 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['a11yr', 22:10:38 INFO - 'ts_paint', 22:10:38 INFO - 'tpaint', 22:10:38 INFO - 'sessionrestore', 22:10:38 INFO - 'sessionrestore_no_auto_restore', 22:10:38 INFO - 'tabpaint']}, 22:10:38 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['a11yr', 22:10:38 INFO - 'ts_paint', 22:10:38 INFO - 'tpaint', 22:10:38 INFO - 'sessionrestore', 22:10:38 INFO - 'sessionrestore_no_auto_restore', 22:10:38 INFO - 'tabpaint']}, 22:10:38 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['tsvgx', 22:10:38 INFO - 'tsvgr_opacity', 22:10:38 INFO - 'tart', 22:10:38 INFO - 'tscrollx', 22:10:38 INFO - 'cart']}, 22:10:38 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:10:38 INFO - 'tsvgr_opacity', 22:10:38 INFO - 'tart', 22:10:38 INFO - 'tscrollx', 22:10:38 INFO - 'cart']}, 22:10:38 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'talos_options': ['--disable-e10s'], 22:10:38 INFO - 'tests': ['tp5o']}, 22:10:38 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'tests': ['tp5o']}, 22:10:38 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'talos_options': ['--disable-e10s', 22:10:38 INFO - '--xperf_path', 22:10:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:38 INFO - 'tests': ['tp5n']}, 22:10:38 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:10:38 INFO - 'talos_options': ['--xperf_path', 22:10:38 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:10:38 INFO - 'tests': ['tp5n']}}, 22:10:38 INFO - 'talos.zip': {'path': '', 22:10:38 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:10:38 INFO - Running post-action listener: _resource_record_post_action 22:10:38 INFO - ##### 22:10:38 INFO - ##### Running create-virtualenv step. 22:10:38 INFO - ##### 22:10:38 INFO - Running pre-action listener: _resource_record_pre_action 22:10:38 INFO - Running main action method: create_virtualenv 22:10:38 INFO - Creating virtualenv /builds/slave/test/build/venv 22:10:38 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 22:10:38 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:10:38 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:10:38 INFO - Using real prefix '/usr' 22:10:38 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:10:39 INFO - Installing distribute.............................................................................................................................................................................................done. 22:10:41 INFO - Installing pip.................done. 22:10:41 INFO - Return code: 0 22:10:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 22:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 22:10:41 INFO - Using env: {'DISPLAY': ':0', 22:10:41 INFO - 'HOME': '/home/cltbld', 22:10:41 INFO - 'LANG': 'en_US.UTF-8', 22:10:41 INFO - 'LANGUAGE': 'en_US:en', 22:10:41 INFO - 'LOGNAME': 'cltbld', 22:10:41 INFO - 'MAIL': '/var/mail/cltbld', 22:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:41 INFO - 'MOZ_NO_REMOTE': '1', 22:10:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:41 INFO - 'NO_EM_RESTART': '1', 22:10:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:41 INFO - 'PWD': '/builds/slave/test', 22:10:41 INFO - 'SHELL': '/bin/bash', 22:10:41 INFO - 'SHLVL': '1', 22:10:41 INFO - 'TERM': 'linux', 22:10:41 INFO - 'TMOUT': '86400', 22:10:41 INFO - 'USER': 'cltbld', 22:10:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:41 INFO - '_': '/tools/buildbot/bin/python'} 22:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 22:10:41 INFO - Cleaning up... 22:10:41 INFO - Return code: 0 22:10:41 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:41 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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 22:10:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 22:10:41 INFO - Using env: {'DISPLAY': ':0', 22:10:41 INFO - 'HOME': '/home/cltbld', 22:10:41 INFO - 'LANG': 'en_US.UTF-8', 22:10:41 INFO - 'LANGUAGE': 'en_US:en', 22:10:41 INFO - 'LOGNAME': 'cltbld', 22:10:41 INFO - 'MAIL': '/var/mail/cltbld', 22:10:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:41 INFO - 'MOZ_NO_REMOTE': '1', 22:10:41 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:41 INFO - 'NO_EM_RESTART': '1', 22:10:41 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:41 INFO - 'PWD': '/builds/slave/test', 22:10:41 INFO - 'SHELL': '/bin/bash', 22:10:41 INFO - 'SHLVL': '1', 22:10:41 INFO - 'TERM': 'linux', 22:10:41 INFO - 'TMOUT': '86400', 22:10:41 INFO - 'USER': 'cltbld', 22:10:41 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:41 INFO - '_': '/tools/buildbot/bin/python'} 22:10:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:41 INFO - Downloading/unpacking psutil>=0.7.1 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:10:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:43 INFO - Installing collected packages: psutil 22:10:43 INFO - Running setup.py install for psutil 22:10:43 INFO - building 'psutil._psutil_linux' extension 22:10:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 22:10:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_linux.so 22:10:43 INFO - building 'psutil._psutil_posix' extension 22:10:43 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -I/usr/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o 22:10:43 INFO - gcc -pthread -shared -Wl,-O1 -Wl,-Bsymbolic-functions -Wl,-Bsymbolic-functions -Wl,-z,relro build/temp.linux-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.linux-x86_64-2.7/psutil/_psutil_posix.so 22:10:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:10:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:10:44 INFO - Successfully installed psutil 22:10:44 INFO - Cleaning up... 22:10:44 INFO - Return code: 0 22:10:44 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 22:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 22:10:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 22:10:44 INFO - Using env: {'DISPLAY': ':0', 22:10:44 INFO - 'HOME': '/home/cltbld', 22:10:44 INFO - 'LANG': 'en_US.UTF-8', 22:10:44 INFO - 'LANGUAGE': 'en_US:en', 22:10:44 INFO - 'LOGNAME': 'cltbld', 22:10:44 INFO - 'MAIL': '/var/mail/cltbld', 22:10:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:44 INFO - 'MOZ_NO_REMOTE': '1', 22:10:44 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:44 INFO - 'NO_EM_RESTART': '1', 22:10:44 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:44 INFO - 'PWD': '/builds/slave/test', 22:10:44 INFO - 'SHELL': '/bin/bash', 22:10:44 INFO - 'SHLVL': '1', 22:10:44 INFO - 'TERM': 'linux', 22:10:44 INFO - 'TMOUT': '86400', 22:10:44 INFO - 'USER': 'cltbld', 22:10:44 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:44 INFO - '_': '/tools/buildbot/bin/python'} 22:10:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:44 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 22:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:46 INFO - Downloading mozsystemmonitor-0.0.tar.gz 22:10:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:10:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 22:10:46 INFO - Installing collected packages: mozsystemmonitor 22:10:46 INFO - Running setup.py install for mozsystemmonitor 22:10:46 INFO - Successfully installed mozsystemmonitor 22:10:46 INFO - Cleaning up... 22:10:46 INFO - Return code: 0 22:10:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:10:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:10:46 INFO - Using env: {'DISPLAY': ':0', 22:10:46 INFO - 'HOME': '/home/cltbld', 22:10:46 INFO - 'LANG': 'en_US.UTF-8', 22:10:46 INFO - 'LANGUAGE': 'en_US:en', 22:10:46 INFO - 'LOGNAME': 'cltbld', 22:10:46 INFO - 'MAIL': '/var/mail/cltbld', 22:10:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:46 INFO - 'MOZ_NO_REMOTE': '1', 22:10:46 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:46 INFO - 'NO_EM_RESTART': '1', 22:10:46 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:46 INFO - 'PWD': '/builds/slave/test', 22:10:46 INFO - 'SHELL': '/bin/bash', 22:10:46 INFO - 'SHLVL': '1', 22:10:46 INFO - 'TERM': 'linux', 22:10:46 INFO - 'TMOUT': '86400', 22:10:46 INFO - 'USER': 'cltbld', 22:10:46 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:46 INFO - '_': '/tools/buildbot/bin/python'} 22:10:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:46 INFO - Downloading/unpacking blobuploader==1.2.4 22:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:48 INFO - Downloading blobuploader-1.2.4.tar.gz 22:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:10:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:10:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:49 INFO - Downloading docopt-0.6.1.tar.gz 22:10:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:10:49 INFO - Installing collected packages: blobuploader, requests, docopt 22:10:49 INFO - Running setup.py install for blobuploader 22:10:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:10:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:10:49 INFO - Running setup.py install for requests 22:10:49 INFO - Running setup.py install for docopt 22:10:49 INFO - Successfully installed blobuploader requests docopt 22:10:49 INFO - Cleaning up... 22:10:49 INFO - Return code: 0 22:10:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:49 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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:10:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:10:49 INFO - Using env: {'DISPLAY': ':0', 22:10:49 INFO - 'HOME': '/home/cltbld', 22:10:49 INFO - 'LANG': 'en_US.UTF-8', 22:10:49 INFO - 'LANGUAGE': 'en_US:en', 22:10:49 INFO - 'LOGNAME': 'cltbld', 22:10:49 INFO - 'MAIL': '/var/mail/cltbld', 22:10:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:49 INFO - 'MOZ_NO_REMOTE': '1', 22:10:49 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:49 INFO - 'NO_EM_RESTART': '1', 22:10:49 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:49 INFO - 'PWD': '/builds/slave/test', 22:10:49 INFO - 'SHELL': '/bin/bash', 22:10:49 INFO - 'SHLVL': '1', 22:10:49 INFO - 'TERM': 'linux', 22:10:49 INFO - 'TMOUT': '86400', 22:10:49 INFO - 'USER': 'cltbld', 22:10:49 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:49 INFO - '_': '/tools/buildbot/bin/python'} 22:10:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:50 INFO - Running setup.py (path:/tmp/pip-CqevJ1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:50 INFO - Running setup.py (path:/tmp/pip-KZz0Oz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:50 INFO - Running setup.py (path:/tmp/pip-IX0N4i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:50 INFO - Running setup.py (path:/tmp/pip-k4XLtM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:50 INFO - Running setup.py (path:/tmp/pip-Usj7u6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:50 INFO - Running setup.py (path:/tmp/pip-HTrPkS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:50 INFO - Running setup.py (path:/tmp/pip-heFKUe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:50 INFO - Running setup.py (path:/tmp/pip-YYZaaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:50 INFO - Running setup.py (path:/tmp/pip-s9rIPt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:50 INFO - Running setup.py (path:/tmp/pip-74RufC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:50 INFO - Running setup.py (path:/tmp/pip-WF5GIu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:50 INFO - Running setup.py (path:/tmp/pip-QExqn6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:50 INFO - Running setup.py (path:/tmp/pip-R5sVyG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:50 INFO - Running setup.py (path:/tmp/pip-KHVshJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:51 INFO - Running setup.py (path:/tmp/pip-p8cdAY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:51 INFO - Running setup.py (path:/tmp/pip-vJlgx0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:51 INFO - Running setup.py (path:/tmp/pip-lB3y2A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:10:51 INFO - Running setup.py install for manifestparser 22:10:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:10:51 INFO - Running setup.py install for mozcrash 22:10:51 INFO - Running setup.py install for mozdebug 22:10:51 INFO - Running setup.py install for mozdevice 22:10:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:10:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:10:51 INFO - Running setup.py install for mozfile 22:10:51 INFO - Running setup.py install for mozhttpd 22:10:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:10:51 INFO - Running setup.py install for mozinfo 22:10:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:10:51 INFO - Running setup.py install for mozInstall 22:10:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Running setup.py install for mozleak 22:10:52 INFO - Running setup.py install for mozlog 22:10:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Running setup.py install for moznetwork 22:10:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Running setup.py install for mozprocess 22:10:52 INFO - Running setup.py install for mozprofile 22:10:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Running setup.py install for mozrunner 22:10:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:10:52 INFO - Running setup.py install for mozscreenshot 22:10:52 INFO - Running setup.py install for moztest 22:10:52 INFO - Running setup.py install for mozversion 22:10:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:10:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:10:53 INFO - Cleaning up... 22:10:53 INFO - Return code: 0 22:10:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:53 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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:10:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:10:53 INFO - Using env: {'DISPLAY': ':0', 22:10:53 INFO - 'HOME': '/home/cltbld', 22:10:53 INFO - 'LANG': 'en_US.UTF-8', 22:10:53 INFO - 'LANGUAGE': 'en_US:en', 22:10:53 INFO - 'LOGNAME': 'cltbld', 22:10:53 INFO - 'MAIL': '/var/mail/cltbld', 22:10:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:53 INFO - 'MOZ_NO_REMOTE': '1', 22:10:53 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:53 INFO - 'NO_EM_RESTART': '1', 22:10:53 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:53 INFO - 'PWD': '/builds/slave/test', 22:10:53 INFO - 'SHELL': '/bin/bash', 22:10:53 INFO - 'SHLVL': '1', 22:10:53 INFO - 'TERM': 'linux', 22:10:53 INFO - 'TMOUT': '86400', 22:10:53 INFO - 'USER': 'cltbld', 22:10:53 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:53 INFO - '_': '/tools/buildbot/bin/python'} 22:10:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:10:53 INFO - Running setup.py (path:/tmp/pip-K70gfn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:10:53 INFO - Running setup.py (path:/tmp/pip-K4VfWu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:10:53 INFO - Running setup.py (path:/tmp/pip-yb0ltJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:10:53 INFO - Running setup.py (path:/tmp/pip-Zrkajk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:10:53 INFO - Running setup.py (path:/tmp/pip-lT8rJX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:10:53 INFO - Running setup.py (path:/tmp/pip-IgVVW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:10:53 INFO - Running setup.py (path:/tmp/pip-MXW2YY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:10:53 INFO - Running setup.py (path:/tmp/pip-IzePwM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:10:53 INFO - Running setup.py (path:/tmp/pip-mc5M4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:10:53 INFO - Running setup.py (path:/tmp/pip-yAr7mR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:10:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:10:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:10:53 INFO - Running setup.py (path:/tmp/pip-kLkovv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:10:54 INFO - Running setup.py (path:/tmp/pip-mAXgHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:10:54 INFO - Running setup.py (path:/tmp/pip-2JA0QY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:10:54 INFO - Running setup.py (path:/tmp/pip-XHIDu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:54 INFO - Running setup.py (path:/tmp/pip-Yu1F1Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:10:54 INFO - Running setup.py (path:/tmp/pip-VyCN8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 22:10:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:10:54 INFO - Running setup.py (path:/tmp/pip-WCQtMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:10:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:10:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:56 INFO - Downloading blessings-1.6.tar.gz 22:10:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:10:56 INFO - Installing collected packages: blessings 22:10:56 INFO - Running setup.py install for blessings 22:10:56 INFO - Successfully installed blessings 22:10:56 INFO - Cleaning up... 22:10:56 INFO - Return code: 0 22:10:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:10:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:10:56 INFO - Reading from file tmpfile_stdout 22:10:56 INFO - Current package versions: 22:10:56 INFO - argparse == 1.2.1 22:10:56 INFO - blessings == 1.6 22:10:56 INFO - blobuploader == 1.2.4 22:10:56 INFO - docopt == 0.6.1 22:10:56 INFO - manifestparser == 1.1 22:10:56 INFO - mozInstall == 1.12 22:10:56 INFO - mozcrash == 0.17 22:10:56 INFO - mozdebug == 0.1 22:10:56 INFO - mozdevice == 0.48 22:10:56 INFO - mozfile == 1.2 22:10:56 INFO - mozhttpd == 0.7 22:10:56 INFO - mozinfo == 0.9 22:10:56 INFO - mozleak == 0.1 22:10:56 INFO - mozlog == 3.1 22:10:56 INFO - moznetwork == 0.27 22:10:56 INFO - mozprocess == 0.22 22:10:56 INFO - mozprofile == 0.28 22:10:56 INFO - mozrunner == 6.11 22:10:56 INFO - mozscreenshot == 0.1 22:10:56 INFO - mozsystemmonitor == 0.0 22:10:56 INFO - moztest == 0.7 22:10:56 INFO - mozversion == 1.4 22:10:56 INFO - psutil == 3.1.1 22:10:56 INFO - requests == 1.2.3 22:10:56 INFO - wsgiref == 0.1.2 22:10:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:56 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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 22:10:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:10:56 INFO - Using env: {'DISPLAY': ':0', 22:10:56 INFO - 'HOME': '/home/cltbld', 22:10:56 INFO - 'LANG': 'en_US.UTF-8', 22:10:56 INFO - 'LANGUAGE': 'en_US:en', 22:10:56 INFO - 'LOGNAME': 'cltbld', 22:10:56 INFO - 'MAIL': '/var/mail/cltbld', 22:10:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:56 INFO - 'MOZ_NO_REMOTE': '1', 22:10:56 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:56 INFO - 'NO_EM_RESTART': '1', 22:10:56 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:56 INFO - 'PWD': '/builds/slave/test', 22:10:56 INFO - 'SHELL': '/bin/bash', 22:10:56 INFO - 'SHLVL': '1', 22:10:56 INFO - 'TERM': 'linux', 22:10:56 INFO - 'TMOUT': '86400', 22:10:56 INFO - 'USER': 'cltbld', 22:10:56 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:56 INFO - '_': '/tools/buildbot/bin/python'} 22:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 22:10:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 22:10:57 INFO - Cleaning up... 22:10:57 INFO - Return code: 0 22:10:57 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 22:10:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 22:10:57 INFO - Using env: {'DISPLAY': ':0', 22:10:57 INFO - 'HOME': '/home/cltbld', 22:10:57 INFO - 'LANG': 'en_US.UTF-8', 22:10:57 INFO - 'LANGUAGE': 'en_US:en', 22:10:57 INFO - 'LOGNAME': 'cltbld', 22:10:57 INFO - 'MAIL': '/var/mail/cltbld', 22:10:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:57 INFO - 'MOZ_NO_REMOTE': '1', 22:10:57 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:57 INFO - 'NO_EM_RESTART': '1', 22:10:57 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:57 INFO - 'PWD': '/builds/slave/test', 22:10:57 INFO - 'SHELL': '/bin/bash', 22:10:57 INFO - 'SHLVL': '1', 22:10:57 INFO - 'TERM': 'linux', 22:10:57 INFO - 'TMOUT': '86400', 22:10:57 INFO - 'USER': 'cltbld', 22:10:57 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:57 INFO - '_': '/tools/buildbot/bin/python'} 22:10:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:57 INFO - Downloading/unpacking jsonschema 22:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:10:59 INFO - Downloading/unpacking functools32 (from jsonschema) 22:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:59 INFO - Downloading functools32-3.2.3-2.tar.gz 22:10:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:10:59 INFO - warning: no files found matching '*.txt' 22:10:59 INFO - no previously-included directories found matching 'build' 22:10:59 INFO - no previously-included directories found matching 'dist' 22:10:59 INFO - no previously-included directories found matching '.git*' 22:10:59 INFO - Installing collected packages: jsonschema, functools32 22:10:59 INFO - Running setup.py install for functools32 22:10:59 INFO - warning: no files found matching '*.txt' 22:10:59 INFO - no previously-included directories found matching 'build' 22:10:59 INFO - no previously-included directories found matching 'dist' 22:10:59 INFO - no previously-included directories found matching '.git*' 22:10:59 INFO - Successfully installed jsonschema functools32 22:10:59 INFO - Cleaning up... 22:10:59 INFO - Return code: 0 22:10:59 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 22:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:10:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 22:10:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:10:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:10:59 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 0x7f52fc764f10>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x24cc8a0>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x25975a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, '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': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 'LANGUAGE': 'en_US:en', 'MAIL': '/var/mail/cltbld', 'SHLVL': '1', 'XPCOM_DEBUG_BREAK': 'warn', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 'TMOUT': '86400', 'PWD': '/builds/slave/test', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 'NO_EM_RESTART': '1', 'HOME': '/home/cltbld', 'DISPLAY': ':0', '_': '/tools/buildbot/bin/python'}}, attempt #1 22:10:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 22:10:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 22:10:59 INFO - Using env: {'DISPLAY': ':0', 22:10:59 INFO - 'HOME': '/home/cltbld', 22:10:59 INFO - 'LANG': 'en_US.UTF-8', 22:10:59 INFO - 'LANGUAGE': 'en_US:en', 22:10:59 INFO - 'LOGNAME': 'cltbld', 22:10:59 INFO - 'MAIL': '/var/mail/cltbld', 22:10:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:10:59 INFO - 'MOZ_NO_REMOTE': '1', 22:10:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:10:59 INFO - 'NO_EM_RESTART': '1', 22:10:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:10:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:10:59 INFO - 'PWD': '/builds/slave/test', 22:10:59 INFO - 'SHELL': '/bin/bash', 22:10:59 INFO - 'SHLVL': '1', 22:10:59 INFO - 'TERM': 'linux', 22:10:59 INFO - 'TMOUT': '86400', 22:10:59 INFO - 'USER': 'cltbld', 22:10:59 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:10:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:10:59 INFO - '_': '/tools/buildbot/bin/python'} 22:10:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:10:59 INFO - Downloading/unpacking flake8 22:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:10:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:10:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:10:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:01 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 22:11:01 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:01 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 22:11:01 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:02 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 22:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:11:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:11:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 22:11:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:11:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:11:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:11:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:11:02 INFO - no previously-included directories found matching 'docs/_build' 22:11:02 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 22:11:02 INFO - Running setup.py install for pep8 22:11:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 22:11:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 22:11:02 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 22:11:02 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 22:11:02 INFO - no previously-included directories found matching 'docs/_build' 22:11:02 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 22:11:02 INFO - Successfully installed flake8 mccabe pyflakes pep8 22:11:02 INFO - Cleaning up... 22:11:02 INFO - Return code: 0 22:11:02 INFO - Running post-action listener: _resource_record_post_action 22:11:02 INFO - Running post-action listener: _start_resource_monitoring 22:11:02 INFO - Starting resource monitoring. 22:11:02 INFO - ##### 22:11:02 INFO - ##### Running install step. 22:11:02 INFO - ##### 22:11:02 INFO - Running pre-action listener: _resource_record_pre_action 22:11:02 INFO - Running main action method: install 22:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:11:02 INFO - Reading from file tmpfile_stdout 22:11:02 INFO - Detecting whether we're running mozinstall >=1.0... 22:11:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:11:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:11:03 INFO - Reading from file tmpfile_stdout 22:11:03 INFO - Output received: 22:11:03 INFO - Usage: mozinstall [options] installer 22:11:03 INFO - Options: 22:11:03 INFO - -h, --help show this help message and exit 22:11:03 INFO - -d DEST, --destination=DEST 22:11:03 INFO - Directory to install application into. [default: 22:11:03 INFO - "/builds/slave/test"] 22:11:03 INFO - --app=APP Application being installed. [default: firefox] 22:11:03 INFO - mkdir: /builds/slave/test/build/application 22:11:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2', '--destination', '/builds/slave/test/build/application'] 22:11:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 --destination /builds/slave/test/build/application 22:11:18 INFO - Reading from file tmpfile_stdout 22:11:18 INFO - Output received: 22:11:18 INFO - /builds/slave/test/build/application/firefox/firefox 22:11:18 INFO - Running post-action listener: _resource_record_post_action 22:11:18 INFO - ##### 22:11:18 INFO - ##### Running run-tests step. 22:11:18 INFO - ##### 22:11:18 INFO - Running pre-action listener: _resource_record_pre_action 22:11:18 INFO - Running main action method: run_tests 22:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:11:18 INFO - Python 2.7.3 22:11:18 INFO - Return code: 0 22:11:18 INFO - grabbing minidump binary from tooltool 22:11: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')]} 22:11:18 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 0x2626410>, '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 0x2627310>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x26277a0>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:11:18 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 22:11:18 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 22:11:18 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:11:18 INFO - Return code: 0 22:11:18 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 22:11:18 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:11:18 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:11:18 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 22:11:18 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:11:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 22:11:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 22:11:19 INFO - Return code: 0 22:11:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 22:11:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip --title talos-linux64-ix-041 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 22:11:19 INFO - Using env: {'DISPLAY': ':0', 22:11:19 INFO - 'HOME': '/home/cltbld', 22:11:19 INFO - 'LANG': 'en_US.UTF-8', 22:11:19 INFO - 'LANGUAGE': 'en_US:en', 22:11:19 INFO - 'LOGNAME': 'cltbld', 22:11:19 INFO - 'MAIL': '/var/mail/cltbld', 22:11:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:11:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 22:11:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:11:19 INFO - 'MOZ_NO_REMOTE': '1', 22:11:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:11:19 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 22:11:19 INFO - 'NO_EM_RESTART': '1', 22:11:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 22:11:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:11:19 INFO - 'PWD': '/builds/slave/test', 22:11:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:11:19 INFO - 'SHELL': '/bin/bash', 22:11:19 INFO - 'SHLVL': '1', 22:11:19 INFO - 'TERM': 'linux', 22:11:19 INFO - 'TMOUT': '86400', 22:11:19 INFO - 'USER': 'cltbld', 22:11:19 INFO - 'XDG_SESSION_COOKIE': '8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948', 22:11:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:11:19 INFO - '_': '/tools/buildbot/bin/python'} 22:11:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-041', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 22:11:19 INFO - mozversion application_buildid: 20160418205131 22:11:19 INFO - mozversion application_changeset: cb65ec85049efa44b18e2e8d06af26768c3bfb94 22:11:19 INFO - mozversion application_display_name: Nightly 22:11:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:11:19 INFO - mozversion application_name: Firefox 22:11:19 INFO - mozversion application_remotingname: firefox 22:11:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 22:11:19 INFO - mozversion application_vendor: Mozilla 22:11:19 INFO - mozversion application_version: 48.0a1 22:11:19 INFO - mozversion platform_buildid: 20160418205131 22:11:19 INFO - mozversion platform_changeset: cb65ec85049efa44b18e2e8d06af26768c3bfb94 22:11:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 22:11:19 INFO - mozversion platform_version: 48.0a1 22:11:19 INFO - using testdate: 1461042679 22:11:19 INFO - actual date: 1461042679 22:11:19 INFO - starting webserver on 'localhost:37041' 22:11:19 INFO - SUITE-START | Running 2 tests 22:11:19 INFO - TEST-START | dromaeo_css 22:11:19 INFO - operating with platform_type : linux_ 22:11:19 INFO - Initialising browser for dromaeo_css test... 22:11:19 INFO - TEST-INFO | started process 17625 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppmamsV/profile http://localhost:37041/getInfo.html) 22:11:24 INFO - PROCESS | 17625 | __metrics Screen width/height:1600/1200 22:11:24 INFO - PROCESS | 17625 | colorDepth:24 22:11:24 INFO - PROCESS | 17625 | Browser inner width/height: 1024/697 22:11:24 INFO - PROCESS | 17625 | __metrics 22:11:25 ERROR - PROCESS | 17625 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:11:26 INFO - TEST-INFO | 17625: exit 0 22:11:26 INFO - Browser initialized. 22:11:26 INFO - Running cycle 1/1 for dromaeo_css test... 22:11:26 INFO - TEST-INFO | started process 17707 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmppmamsV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:11:38 INFO - PROCESS | 17707 | RSS: Main: 154525696 22:11:38 INFO - PROCESS | 17707 | 22:12:29 INFO - PROCESS | 17707 | 1461042749598 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 22:14:41 INFO - PROCESS | 17707 | Cycle 1(1): loaded http://localhost:37041/tests/dromaeo/cssquery-dojo.html (next: http://localhost:37041/tests/dromaeo/cssquery-ext.html) 22:14:42 INFO - PROCESS | 17707 | RSS: Main: 259358720 22:14:42 INFO - PROCESS | 17707 | 22:17:44 INFO - PROCESS | 17707 | Cycle 1(1): loaded http://localhost:37041/tests/dromaeo/cssquery-ext.html (next: http://localhost:37041/tests/dromaeo/cssquery-jquery.html) 22:17:45 INFO - PROCESS | 17707 | RSS: Main: 233181184 22:17:45 INFO - PROCESS | 17707 | 22:20:47 INFO - PROCESS | 17707 | Cycle 1(1): loaded http://localhost:37041/tests/dromaeo/cssquery-jquery.html (next: http://localhost:37041/tests/dromaeo/cssquery-mootools.html) 22:20:48 INFO - PROCESS | 17707 | RSS: Main: 234885120 22:20:48 INFO - PROCESS | 17707 | 22:23:50 INFO - PROCESS | 17707 | Cycle 1(1): loaded http://localhost:37041/tests/dromaeo/cssquery-mootools.html (next: http://localhost:37041/tests/dromaeo/cssquery-prototype.html) 22:23:50 INFO - PROCESS | 17707 | RSS: Main: 231608320 22:23:50 INFO - PROCESS | 17707 | 22:26:53 INFO - PROCESS | 17707 | Cycle 1(1): loaded http://localhost:37041/tests/dromaeo/cssquery-prototype.html (next: http://localhost:37041/tests/dromaeo/cssquery-yui.html) 22:26:54 INFO - PROCESS | 17707 | RSS: Main: 210014208 22:26:54 INFO - PROCESS | 17707 | 22:29:56 INFO - PROCESS | 17707 | Cycle 1(1): loaded http://localhost:37041/tests/dromaeo/cssquery-yui.html (next: http://localhost:37041/tests/dromaeo/cssquery-dojo.html) 22:29:57 INFO - PROCESS | 17707 | RSS: Main: 268111872 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | __start_tp_report 22:29:57 INFO - PROCESS | 17707 | _x_x_mozilla_page_load 22:29:57 INFO - PROCESS | 17707 | _x_x_mozilla_page_load_details 22:29:57 INFO - PROCESS | 17707 | |i|pagename|runs| 22:29:57 INFO - PROCESS | 17707 | |0;dojo.html;2598.4153565447778;2659.8138130330885;2677.076408639019;2687.2744359023063;2698.27146670399;4522.366868638385;4580.656450766193;4580.908381832362;4590.839320623779;4602.171609110367;4595;4614.884627884305;4634.745089020103;4654;4664.533546645333;4223.61987421132;4238.02525419153;4264.616184543406;4285.357196420547;4391.626711729491;4336.653067754588;4339.891502712426;4353.064091220387;4385.802638881242;4397.692161548693;4141.088960428701;4217.388478670576;4243.130158317537;4288.892777680568;4315.3095504719095;4288.935665965013;4292.29177185764;4295.312749959991;4307.504636966758;4336.436263285753;4258.5528519505315;4270.871873843789;4276.657867370603;4281.357796330544;4321.438213032317;4407.2507673695545;4515.706479078849;4517.706349087299;4525.796339164745;4595.6323494120725;4860.076585448754;4864.756762161913;4901.166801643693;4987.177115775893;4992.976439829826;4940.8764780880765;4942.654014219006;4951.03454826309;4958.429780575244;4960.156773348539;4615.26155815068;4620.4686461056735;4621.0295837873755;4622.884427889329;4672.532746725335;4883.560479556786;5078.466760990117;5131.923021154686;5156.845294641092;5183.222516622462;4857.077155340474;4858.514148585184;4865.197242455026;4865.635077369141;4916.459189489154;4822.445418776815;4918.827841025547;4920.188168952082;4927.728974906343;4962.851114466572;4858.73276969763;4869.245266983623;4924.507549245047;4941;4943.110240156806;4844.781984810692;4868.41579010521;4869.050535145683;4876.975615121902;4921.803127874917;4013.3779264214095;4101.343784994387;4109.691773117028;4110.486189226347;4145.54399016108;3614.6023937366867;3614.945775813365;3660.5607327120383;3697.630236976335;3729.21686445844;4314.956850431518;4324.567543245713;4328.761918094535;4345.044090300129;4368.148211098805;4767.785449654774;4967.5777558907175;4986.326845875833;4987.501249874984;4987.62592805541;4238.682098842599;4277.422547956048;4292.914141717149;4313;4334.696571239983;4452.9090372858855;4477.955220447819;4478.865634030984;4492.842750503782;4520.208963431386;4603.562661547147;4696.600788932979;4746.169420351424;4758.428988521399;4759.809607615656;5034.219695947134;5039.949600503948;5045.066662667418;5046.318746969112;5099;4729.834455794031;4784.138855005993;4786.545278198566;4789.928151077806;4833.951660483491;4769.379980602499;4798.448178459522;4872.731999740048;4960.553550180501;4982.950170498394;4893.486183950777;4905.975470122627;4927.679700819435;4966.379202599675;4995.525425084611;4821.083994041121;4824.927626085681;4828.7102773833685;4829.517048295143;4875.878103047452;4677.532246775295;4758.571728544448;4826.131296366547;4838.959623680926;4847.442544107473;4750.14497390473;4756.548127927841;4764.356811830358;4773.188557945088;4824.276358546246;4841.540053694894;4863.610911127031;4866.80532778699;4869.31829543857;4916.311716359784;4565.13262480141;4642.675012749075;4723.055388922161;4756.33411322422;4819.95180048195 22:29:57 INFO - PROCESS | 17707 | |1;ext.html;575.4044563876389;599.1073300781836;606.4148097086312;614.6895817612111;622.7384498510627;34199.31601367971;35208.823955880245;35347.64652353476;35348.82325588375;35359.11602209946;37032.259354812886;37875.43186852199;37877;37955;37956;7539.623018849063;7728.845423091563;7736.535807851519;7739.148693643695;7745.070591529025;7653.387728981696;7748.922510774876;7765.029371328584;7766.417518686093;7773;7744.457887947846;7792.376609871225;7807.804804879866;7808.843823123534;7813.726519571816;7719.112302085276;7776.377889768805;7787.610619469004;7789.10425301092;7789.805254868617;7568.848623027564;7654.119776225694;7666.540007599506;7671.27122923327;7676.577788221645;36971.630283697086;37166;37482.62517374819;37503.624963750284;37508.624913750784;3018.9396212075635;3165.208697825544;3349.129226401129;3402.2855200407944;3552.0942159749357;27995.86002069997;28149.85925070382;28405.857970710014;29065.418691626048;29350.477942646805;3125.6092988376454;3171.8255495947947;3194.952075718866;3218.5333126696496;3276.8033917965;226233;227026;227658;227961;228752;32661.51007734886;33590.64865459394;33636.32727345439;50522.49477505178;51224.74387628038;30927.381452371275;31377.215569610486;31395.84302078475;31446;31446.371072578877;13214.141080829715;13404.865951340556;13413.865861341457;13421.328933553283;13426.530071447452;10905.073068789083;10985.670429887115;10997.945010274898;11026.503807328689;11027.17296202788;8082.757517274491;8094.595270236582;8124.715634952749;8157.959210203911;8197.95901020503;3146.7639927005107;3203.1511649412923;3206.278587317835;3275.2958114005605;3279.442494775894;875.5272153037416;883.1301168349171;949.292776881228;978.8972157923315;981.3915372469115;27605.585916211272;27714;27809.72190278071;28096.85951570229;28201.153965381072;1011.261778901406;1020.2246292817364;1031.3863251365426;1076.0046956565145;1121.2487633285718;14933.17867517297;14982.40070397172;15029.549113526611;15154.696906062038;15262;320939;328086;328345;329036;329927.4087109562;46452;46981.765091174326;47045;47110.52889471062;47240.5275947243;1066.1737153705785;1108.8835672254459;1159.4318783796048;1241.9068569857477;1253.6489782860813;26664;27246.910123594833;27356.726432735417;27571.586426204023;27935.860320698266;37743.24513509659;38139.42790858084;38216.23567528578;38257.23485530329;38298;212933.57730649307;215221;215239;215342;215398;24886.25341239766;38926.610733892296;40077.198456030135;40129.39805902856;40392;38617.61382386253;39164.216715666094;39644.207115858095;39939;40110;33677;33974.320513590086;34291.82854085714;34332.656673432946;34555.13612159716;33711;34070.318593628486;34143.65856341405;34374.48438273478;34663.480047798796;34095.65904340925;34302.82848585741;34485;34837;34850.651493484744;30287.242818929702;30977.225569360948;31126.895069834358;31241.25395284;31316.843415782776;24228.515429690953;24316;24440.26679199627;24460;24669.753302466746 22:29:57 INFO - PROCESS | 17707 | |2;jquery.html;373.4976456665784;379.1923203576382;382.4454540915671;383.56465411757654;396.16804710108795;15787.526374208777;16142.919285403572;17092.572685682862;17108.657826843457;17111.31554737809;15575;15760.211989400541;16458.09480478573;16492.83507164928;16501;4615.999999999992;4747.501512341206;4763.047390521892;4771.332013518093;4774.713517188962;3395.864165433392;5217.660852044624;5532.529734972532;5535.806246781363;5539;5506.366767821692;5513.228148059275;5518.972405137969;5520.613557051008;5536.944630553683;4855.344528488644;5204.42751297357;5226.451222621646;5230;5231.73841307935;3268.7548433867555;3495.1786330212635;3498.982505087484;3499.265154317596;4797.880052998703;12025;12277.508899644003;12290.078244131726;12892;19751.407457776146;1648.6785076910007;1804.9007304598242;1836.173721825184;1918.213532451688;1935.022813479198;8650.26472749817;8946.597403116875;9101.908980910172;9487.857682134774;9595.376300540513;1652.8512433880887;1713.1776747161432;1721.2426532325737;1725.197783030884;1763.612005358819;68342.65828670874;68772;70064;70719;72479.27520724777;10155.187584993331;10157.288989770645;10159.949200253952;10300.948495257475;10311.48442577868;14289.714205715827;14400.135991840523;14411.207383594003;14416.062955907832;14436.422543098368;7105.289471052956;7244.239354867768;7294.489385742947;7430.777076687708;7435.442341824385;6711.530192886548;6736.2590115087305;6748.358905903931;6803;6820.727170913108;4395.978020109879;4413.646908247332;4422.425084739028;4458.219811532968;4487.955120448753;1751.211954620426;1764.7441121037377;1836.3205613922935;1842.5854182808762;1846.6306738652324;748.6892939430076;873.6025108575583;874.7463235661587;882.7925437522177;885.2608072259735;15570.455034073753;15692.843071569367;16057.67884642324;17895.194716237802;18333.3583324583;882.4352414454754;888.693400776731;892.2193081054078;902.278177458031;947.4599478297305;7253.56478611285;9293.860592091123;9594.520273986273;9623.326367154232;9799.11807937289;284543;291967;292902;398921;407418;78757.60621196857;82774;84426.57786711027;85305;85645.14354856496;976.6581696406201;978.7846673731767;994.6518718448485;1023.6468418395641;1024.109025148126;14699.033589510176;15391.923040384727;15683.372665093724;15807.920960395124;15949.68100638004;33882.66117338795;34209.82895085509;34591.481127783605;34680.826595866856;34922.47616285809;49757.50242497529;50051.249231262285;50201.24698129458;50270.24594631157;50460.49539504558;6718;7121.465890058163;7311.159216690149;7361.595112268877;7388.224236455309;10388;11317.773644526898;11488;11696.90416910512;11742.530298787953;5335.146376579729;8205.876911846446;8953.149450802164;10301.175905927657;10642.308249963728;10013.799724005625;10115.848262276219;10178.847317290394;10325.690229293134;10403.271770975958;10532.631357902437;10721;10740.46297685097;10757.30077544957;10783;7131.716291067658;7173.0675012248075;7189.928100719135;7279.090113735783;7328.78013659591;9048.50233237152;10114.696559103239;10139.543720532742;10527.842082368923;10696.465176741287 22:29:57 INFO - PROCESS | 17707 | |3;mootools.html;1256.8617452080275;1448.9784701785243;1481.0817293278167;1510.9602037249715;1594.186964648029;6569.86860262795;7428.554286742792;7613.048368953874;7645.235476452352;7648.19693932136;7373.631318434083;7511.173770885198;7519.097708275015;7537.24627537246;7552.773416797491;1889.2726300374386;2153.493928926709;2219.456233222859;2237.5413040326694;2252.3468194223656;2263.909443622253;2292.9656055159185;2296.9310920672324;2300.1604414388794;2300.52839167971;2177.129148340661;2245.247841972941;2249.347689170147;2252.5832720946596;2256.1201131558705;1861.9534511637182;2175.878518444512;2253.1663284584642;2257.390504563775;2260.525289689167;2151.505153814616;2174.402039439151;2189.430748005514;2191.0687957618106;2211.9115235390564;7585.051868516435;7607.961960190164;7649.311561959395;7653.693852245903;7656.655450504741;4463.68754187207;4608.5621865922685;4629.120467111238;4646.697964632322;4692.929606055912;2928.1947464447235;2946.3076177098364;2972.910812675623;2991.0428662827685;3005.1885990782366;876.369014309696;880.6521424037469;888.9688860889838;896.8475359188953;912.5118061836952;10386.480675966248;10400.375977441377;10403.739906502322;10420.687379378554;10450.111740502067;4134.152498737752;4236.3857240700545;4360.106178233455;4389.4732632083715;4402.965303153756;8853;8949.642014319485;8959.104089590988;8962.73111806647;8967.103289671111;4089.3252613319064;4769.213079841857;4792.472827988919;4797.760111994457;4808.759562021885;4202.768847713405;4290.249206388869;4318.222719910384;4347;4350.499692535335;3624.731344029621;3681.116532032293;3721.2371463849845;3771.4342848572933;3803.9429408558894;1202.188522747142;1217.9878201217875;1223.076577184238;1238.6779437346174;1241.1808206583792;685.2736099734198;765.2118318132221;773.9729109481141;788.5544667263093;881.8148188880278;5026.698398096126;5130.4356520782685;5165.147750621106;5232.215167724872;5263.631545791758;836.8368168207141;840.995795021021;853.6670698427619;884.6417201033591;921.161742814036;3600.7839529628304;3618.2944325856733;3636.3272794533095;3638.4542318651884;3718.8698395556567;9249.630014799468;9527.237820974306;9570.138687518156;9583.568739406743;9699.70900872975;9486.857697134548;9588.66439674608;9597.184239339595;9606.375585586915;9612.134907858186;7170.888512280605;7214.819629509304;7352.485326027234;7392.852142957218;7418.1839997599145;5208.869778255574;5213.113770658922;5222.529972302511;5299.814506492255;5398.433164517669;8739.606717697592;8765.91234087651;8833.955830221064;8835.293176545989;8888.73333799987;8570.957145214234;8811.823763524566;9049.909500904907;9134.177923986874;9162.083791620784;6432;6661.467082633475;6678.365555272241;6774.93225067743;6850.862982740217;7348.301911318416;7379.889301660372;7397.519161254501;7403.925960740324;7452.850942981002;6486.805395838132;6647.5014373921185;6722.663866806738;6762;6766.120404347404;6485.740570377132;6528.967355163384;6745.52781305304;6793.966030169818;6812.114425124702;6244.125822384961;6285;6297.307296197323;6309.936900630935;6312.084747711464;4685.648576356719;4686.92969605463;4689.882752931204;4769.73766442849;4788.856334309976;4569.5887370135515;4734.526547345238;4840.10458065245;4896.216605343128;4960.330355402119 22:29:57 INFO - PROCESS | 17707 | |4;prototype.html;296.11609346101886;298.3704383750287;304.0771259228241;308.5803307501799;309.70887365876087;4966.230234313678;5001.049800537895;5005.874853128669;5008.599312055036;5011.273365362021;4926.408830940283;4934.851954441372;4941.876453088675;4945.752712364385;4953.207486802113;3697.5562932448142;3779.7543159694533;3908.921821563567;3946.842126314944;3971;3892.143728379752;3934.527856657205;3947.7828719420563;3951.111000024986;3966.2067586482676;3662.2675464907124;3888.1446081862086;3910.5502867170353;3916.647501724844;3922.9803850980707;3783.432485127239;3894.3768996960353;3903.199844031995;3906.160175562268;3907.1404291056065;3445.500402441632;3446.138465383654;3458.581981387644;3463.8960831175104;3467.150548115728;4761.262004389325;4775.259834725588;4786.712797232177;4796.688215265996;4802.279658051285;4259.10558782656;4426.7122637028815;4471.5081341052455;4478.8432404866;4483.798229079699;5174.43081261061;5179.876250737218;5180.274761533351;5197.220416937452;5214.582833373321;3774.660280574734;3792.222594368148;3795.2599243147597;3797.373433383488;3801.239752049601;5240.895182096375;5398.082326004589;5448.400675925645;5448.536874365724;5466.753996070186;3861.5366156061455;3880.20455806559;3888.3972984187494;3893.9415908761393;3902.492675952108;3921.6078392161126;3924.4309575111447;3937.9212415751526;3950.1902110067367;3955.7033222508426;4443.577860103285;4462.308342206963;4464.361545340537;4470.329450582439;4471.7987690554;4901.411830580353;5174.663646862942;5182.32629758129;5188.22176673502;5204.4535323791215;1306.6276111308232;1320.7094439223356;1326.085001349066;1331.2012792324529;1332.826732524785;827.5448503323269;882.4528792148909;887.627196577439;898.7213963671152;909.1771946388396;4557.635389168925;4566.817327306937;4591.747453890068;4594.5864872161665;4597.287420449836;5315.654482458705;5319.7340132993195;5333.359986806556;5339.41266460689;5344.839654810362;4474.26174681174;4563;4566.771661416916;4588.220002599566;4599.264117741212;2834.744872961402;2835.2628316637824;2858.285428642839;2862.914112576626;2863.2269287292315;5008.098542262428;5036.708297724526;5043;5046.142155833517;5047.646664733434;5233.973830130825;5444.945550544444;5446.992306423324;5450.781968721286;5457.83626491198;4990.850274491771;5035.924461133086;5039.899202016012;5043.798248070036;5051.444341122474;5310.097283461898;5319.946800531945;5333.12003519415;5336.279602253801;5339.73301334924;5040.974795126001;5208.192730126836;5297.205419187153;5301.787928482971;5356.946430535645;5148.330717006915;5148.970205958898;5166.664166829144;5167.896642067213;5168.715720635401;3738.5513738351574;3764.7741135531955;3776.773393596393;3783.2055268393947;3784.3566593679693;5198.402183748917;5373.247745315737;5390.946090539044;5398.460153984727;5410.458954104558;5179.533841954091;5209.973950130225;5228.607854410859;5244.082285600081;5256;5215.374155101412;5230.790768369375;5236.109861323508;5245.921311180259;5247.895042099213;4949.7030178189425;5120.18077107661;5173.439147621952;5175.223716442413;5189.714565698923;5040.924386134137;5114.948850511447;5268.631195816256;5296.947030529645;5301.973490132525;5135.537801597725;5148.794048238028;5167.560757335668;5171.172612382001;5177.145770947752 22:29:57 INFO - PROCESS | 17707 | |5;yui.html;661.7253839656543;700.7337211859492;723.1358526560758;729.5440349781387;747.1068164838483;10847.186461015415;11006.229563930508;11053;11054.723631909206;11982.101342399312;12068.094892883026;12108.394580270995;12176.391180440987;12218.083643726712;12288.262704237752;636.707114727226;764.1250767870785;793.714262865368;794.3287921706149;794.5510786405677;795.1174196641723;796.7689370082669;798.309462315099;799.6321692021648;800.0479429478902;842.8904242448473;851.7317045130795;852.3224036890656;852.8720691896203;852.9829403411928;662.7845950066223;820.8604537228686;844.9366297527679;845.9365547583894;846.5936350551739;619.3558698953128;619.8202521268827;620.1163342237294;620.388916916841;621.155228888711;11101.222914395996;11152.275102118336;11164.274322169033;11166.441677916071;11166.609168679139;12221.511139554406;12777;12800.103992720513;13022.413991370411;13194.406251718696;9573.569189386495;9576.605848537865;9587.760305992337;9698;9742.610295588169;3361.882334118319;3403.5745531808525;3438.5014172945034;3443.6556344365363;3446.1556918554866;44987;45017.99999999967;45079;45110.774446127885;45216.321755173696;9872.457014864107;10059.647912323033;10213.182945364353;10274.486275686186;10344.586216551405;18334.541636459195;18364.08179591015;18383.632327353378;18391;18398.17208225633;6536.346365363425;6693.196816382066;6785.728570857111;6879.6216208108035;7033.964830175816;3381.425157723193;3428.5200071989943;3573.910652233715;3693.7045036397026;3792.9569368423354;2430.927072187837;2507.360623041113;2514.4719608881974;2522.608995605684;2526.5325914705472;436.46968932746233;492.6772963708776;499.53293670418276;528.4873672537632;559.30645998961;478.1393491714899;478.9137955168103;547.4334064243488;563.0962305499696;568.7867049856304;7487.962560187164;7732.342750866127;7866.881996770053;8075.071366792777;8582.656693732306;582.4662717180316;613.3835495327168;626.3673689573473;638.5466318913624;642.5277421095491;3865.5747867734526;3881.340172170738;3883.650471457582;3883.747556408881;3999.5200575931076;45338.773306133255;49392.50607493951;49491.50508494941;49618;49644.50355496399;41123.7943810279;43323;44703.10593788106;45035.77482112634;46009.539904600526;39459.210815783525;44430.33354499685;44944.77527612341;45982.310265346045;46744.766276168404;8029.397795165269;8291.419600627898;8374.83250335002;8393.286570641563;8661.480311181349;27886.72113278841;28308.575371369036;28403.289917752223;28424.57363139513;29091.418171636873;34418;34870.476942846384;37902.43146352862;38118;38329.23341533098;11081.944590277319;11142.325841798072;11191.216614836883;11358.94320528392;11586.304821710724;11859.40702964831;11994.700132496757;12201.633950981484;12474;12599.18105323151;10813.837792433276;10937.343759374464;10948.671539853816;11644.2431241969;11659.941700291443;10168.491575421347;10475.319104258198;10575.653158296878;10610.257281990187;10856;10462.163026957978;10817.837732434176;11100.333979961228;11102.666919992413;11377.943110284727;9276.860847087433;9327.720168394959;9512.476813775309;9526.285528585246;9673.661421850202;9228.584713687766;9585.568649410932;9641.614335426504;9716.951415242878;9770.169535589555 22:29:57 INFO - PROCESS | 17707 | __end_tp_report 22:29:57 INFO - PROCESS | 17707 | __start_cc_report 22:29:57 INFO - PROCESS | 17707 | _x_x_mozilla_cycle_collect,1009 22:29:57 INFO - PROCESS | 17707 | __end_cc_report 22:29:57 INFO - PROCESS | 17707 | __startTimestamp1461043797084__endTimestamp 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | ------- Summary: start ------- 22:29:57 INFO - PROCESS | 17707 | Number of tests: 6 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | [#0] dojo.html Cycles:165 Average:4580.58 Median:4746.17 stddev:467.69 (9.9%) stddev-sans-first:442.52 22:29:57 INFO - PROCESS | 17707 | Values: 2598.4 2659.8 2677.1 2687.3 2698.3 4522.4 4580.7 4580.9 4590.8 4602.2 4595.0 4614.9 4634.7 4654.0 4664.5 4223.6 4238.0 4264.6 4285.4 4391.6 4336.7 4339.9 4353.1 4385.8 4397.7 4141.1 4217.4 4243.1 4288.9 4315.3 4288.9 4292.3 4295.3 4307.5 4336.4 4258.6 4270.9 4276.7 4281.4 4321.4 4407.3 4515.7 4517.7 4525.8 4595.6 4860.1 4864.8 4901.2 4987.2 4993.0 4940.9 4942.7 4951.0 4958.4 4960.2 4615.3 4620.5 4621.0 4622.9 4672.5 4883.6 5078.5 5131.9 5156.8 5183.2 4857.1 4858.5 4865.2 4865.6 4916.5 4822.4 4918.8 4920.2 4927.7 4962.9 4858.7 4869.2 4924.5 4941.0 4943.1 4844.8 4868.4 4869.1 4877.0 4921.8 4013.4 4101.3 4109.7 4110.5 4145.5 3614.6 3614.9 3660.6 3697.6 3729.2 4315.0 4324.6 4328.8 4345.0 4368.1 4767.8 4967.6 4986.3 4987.5 4987.6 4238.7 4277.4 4292.9 4313.0 4334.7 4452.9 4478.0 4478.9 4492.8 4520.2 4603.6 4696.6 4746.2 4758.4 4759.8 5034.2 5039.9 5045.1 5046.3 5099.0 4729.8 4784.1 4786.5 4789.9 4834.0 4769.4 4798.4 4872.7 4960.6 4983.0 4893.5 4906.0 4927.7 4966.4 4995.5 4821.1 4824.9 4828.7 4829.5 4875.9 4677.5 4758.6 4826.1 4839.0 4847.4 4750.1 4756.5 4764.4 4773.2 4824.3 4841.5 4863.6 4866.8 4869.3 4916.3 4565.1 4642.7 4723.1 4756.3 4820.0 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | [#1] ext.html Cycles:180 Average:40408.64 Median:18736.30 stddev:68494.32 (365.6%) stddev-sans-first:68621.17 22:29:57 INFO - PROCESS | 17707 | Values: 575.4 599.1 606.4 614.7 622.7 34199.3 35208.8 35347.6 35348.8 35359.1 37032.3 37875.4 37877.0 37955.0 37956.0 7539.6 7728.8 7736.5 7739.1 7745.1 7653.4 7748.9 7765.0 7766.4 7773.0 7744.5 7792.4 7807.8 7808.8 7813.7 7719.1 7776.4 7787.6 7789.1 7789.8 7568.8 7654.1 7666.5 7671.3 7676.6 36971.6 37166.0 37482.6 37503.6 37508.6 3018.9 3165.2 3349.1 3402.3 3552.1 27995.9 28149.9 28405.9 29065.4 29350.5 3125.6 3171.8 3195.0 3218.5 3276.8 226233.0 227026.0 227658.0 227961.0 228752.0 32661.5 33590.6 33636.3 50522.5 51224.7 30927.4 31377.2 31395.8 31446.0 31446.4 13214.1 13404.9 13413.9 13421.3 13426.5 10905.1 10985.7 10997.9 11026.5 11027.2 8082.8 8094.6 8124.7 8158.0 8198.0 3146.8 3203.2 3206.3 3275.3 3279.4 875.5 883.1 949.3 978.9 981.4 27605.6 27714.0 27809.7 28096.9 28201.2 1011.3 1020.2 1031.4 1076.0 1121.2 14933.2 14982.4 15029.5 15154.7 15262.0 320939.0 328086.0 328345.0 329036.0 329927.4 46452.0 46981.8 47045.0 47110.5 47240.5 1066.2 1108.9 1159.4 1241.9 1253.6 26664.0 27246.9 27356.7 27571.6 27935.9 37743.2 38139.4 38216.2 38257.2 38298.0 212933.6 215221.0 215239.0 215342.0 215398.0 24886.3 38926.6 40077.2 40129.4 40392.0 38617.6 39164.2 39644.2 39939.0 40110.0 33677.0 33974.3 34291.8 34332.7 34555.1 33711.0 34070.3 34143.7 34374.5 34663.5 34095.7 34302.8 34485.0 34837.0 34850.7 30287.2 30977.2 31126.9 31241.3 31316.8 24228.5 24316.0 24440.3 24460.0 24669.8 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | [#2] jquery.html Cycles:180 Average:22542.93 Median:199658.58 stddev:56757.89 (28.4%) stddev-sans-first:56892.70 22:29:57 INFO - PROCESS | 17707 | Values: 373.5 379.2 382.4 383.6 396.2 15787.5 16142.9 17092.6 17108.7 17111.3 15575.0 15760.2 16458.1 16492.8 16501.0 4616.0 4747.5 4763.0 4771.3 4774.7 3395.9 5217.7 5532.5 5535.8 5539.0 5506.4 5513.2 5519.0 5520.6 5536.9 4855.3 5204.4 5226.5 5230.0 5231.7 3268.8 3495.2 3499.0 3499.3 4797.9 12025.0 12277.5 12290.1 12892.0 19751.4 1648.7 1804.9 1836.2 1918.2 1935.0 8650.3 8946.6 9101.9 9487.9 9595.4 1652.9 1713.2 1721.2 1725.2 1763.6 68342.7 68772.0 70064.0 70719.0 72479.3 10155.2 10157.3 10159.9 10300.9 10311.5 14289.7 14400.1 14411.2 14416.1 14436.4 7105.3 7244.2 7294.5 7430.8 7435.4 6711.5 6736.3 6748.4 6803.0 6820.7 4396.0 4413.6 4422.4 4458.2 4488.0 1751.2 1764.7 1836.3 1842.6 1846.6 748.7 873.6 874.7 882.8 885.3 15570.5 15692.8 16057.7 17895.2 18333.4 882.4 888.7 892.2 902.3 947.5 7253.6 9293.9 9594.5 9623.3 9799.1 284543.0 291967.0 292902.0 398921.0 407418.0 78757.6 82774.0 84426.6 85305.0 85645.1 976.7 978.8 994.7 1023.6 1024.1 14699.0 15391.9 15683.4 15807.9 15949.7 33882.7 34209.8 34591.5 34680.8 34922.5 49757.5 50051.2 50201.2 50270.2 50460.5 6718.0 7121.5 7311.2 7361.6 7388.2 10388.0 11317.8 11488.0 11696.9 11742.5 5335.1 8205.9 8953.1 10301.2 10642.3 10013.8 10115.8 10178.8 10325.7 10403.3 10532.6 10721.0 10740.5 10757.3 10783.0 7131.7 7173.1 7189.9 7279.1 7328.8 9048.5 10114.7 10139.5 10527.8 10696.5 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | [#3] mootools.html Cycles:180 Average:5115.20 Median:5227.37 stddev:2812.23 (53.8%) stddev-sans-first:2805.16 22:29:57 INFO - PROCESS | 17707 | Values: 1256.9 1449.0 1481.1 1511.0 1594.2 6569.9 7428.6 7613.0 7645.2 7648.2 7373.6 7511.2 7519.1 7537.2 7552.8 1889.3 2153.5 2219.5 2237.5 2252.3 2263.9 2293.0 2296.9 2300.2 2300.5 2177.1 2245.2 2249.3 2252.6 2256.1 1862.0 2175.9 2253.2 2257.4 2260.5 2151.5 2174.4 2189.4 2191.1 2211.9 7585.1 7608.0 7649.3 7653.7 7656.7 4463.7 4608.6 4629.1 4646.7 4692.9 2928.2 2946.3 2972.9 2991.0 3005.2 876.4 880.7 889.0 896.8 912.5 10386.5 10400.4 10403.7 10420.7 10450.1 4134.2 4236.4 4360.1 4389.5 4403.0 8853.0 8949.6 8959.1 8962.7 8967.1 4089.3 4769.2 4792.5 4797.8 4808.8 4202.8 4290.2 4318.2 4347.0 4350.5 3624.7 3681.1 3721.2 3771.4 3803.9 1202.2 1218.0 1223.1 1238.7 1241.2 685.3 765.2 774.0 788.6 881.8 5026.7 5130.4 5165.1 5232.2 5263.6 836.8 841.0 853.7 884.6 921.2 3600.8 3618.3 3636.3 3638.5 3718.9 9249.6 9527.2 9570.1 9583.6 9699.7 9486.9 9588.7 9597.2 9606.4 9612.1 7170.9 7214.8 7352.5 7392.9 7418.2 5208.9 5213.1 5222.5 5299.8 5398.4 8739.6 8765.9 8834.0 8835.3 8888.7 8571.0 8811.8 9049.9 9134.2 9162.1 6432.0 6661.5 6678.4 6774.9 6850.9 7348.3 7379.9 7397.5 7403.9 7452.9 6486.8 6647.5 6722.7 6762.0 6766.1 6485.7 6529.0 6745.5 6794.0 6812.1 6244.1 6285.0 6297.3 6309.9 6312.1 4685.6 4686.9 4689.9 4769.7 4788.9 4569.6 4734.5 4840.1 4896.2 4960.3 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | [#4] prototype.html Cycles:180 Average:4334.52 Median:4951.46 stddev:1251.40 (25.3%) stddev-sans-first:1217.65 22:29:57 INFO - PROCESS | 17707 | Values: 296.1 298.4 304.1 308.6 309.7 4966.2 5001.0 5005.9 5008.6 5011.3 4926.4 4934.9 4941.9 4945.8 4953.2 3697.6 3779.8 3908.9 3946.8 3971.0 3892.1 3934.5 3947.8 3951.1 3966.2 3662.3 3888.1 3910.6 3916.6 3923.0 3783.4 3894.4 3903.2 3906.2 3907.1 3445.5 3446.1 3458.6 3463.9 3467.2 4761.3 4775.3 4786.7 4796.7 4802.3 4259.1 4426.7 4471.5 4478.8 4483.8 5174.4 5179.9 5180.3 5197.2 5214.6 3774.7 3792.2 3795.3 3797.4 3801.2 5240.9 5398.1 5448.4 5448.5 5466.8 3861.5 3880.2 3888.4 3893.9 3902.5 3921.6 3924.4 3937.9 3950.2 3955.7 4443.6 4462.3 4464.4 4470.3 4471.8 4901.4 5174.7 5182.3 5188.2 5204.5 1306.6 1320.7 1326.1 1331.2 1332.8 827.5 882.5 887.6 898.7 909.2 4557.6 4566.8 4591.7 4594.6 4597.3 5315.7 5319.7 5333.4 5339.4 5344.8 4474.3 4563.0 4566.8 4588.2 4599.3 2834.7 2835.3 2858.3 2862.9 2863.2 5008.1 5036.7 5043.0 5046.1 5047.6 5234.0 5444.9 5447.0 5450.8 5457.8 4990.9 5035.9 5039.9 5043.8 5051.4 5310.1 5319.9 5333.1 5336.3 5339.7 5041.0 5208.2 5297.2 5301.8 5356.9 5148.3 5149.0 5166.7 5167.9 5168.7 3738.6 3764.8 3776.8 3783.2 3784.4 5198.4 5373.2 5390.9 5398.5 5410.5 5179.5 5210.0 5228.6 5244.1 5256.0 5215.4 5230.8 5236.1 5245.9 5247.9 4949.7 5120.2 5173.4 5175.2 5189.7 5040.9 5114.9 5268.6 5296.9 5302.0 5135.5 5148.8 5167.6 5171.2 5177.1 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - PROCESS | 17707 | [#5] yui.html Cycles:180 Average:12519.22 Median:44965.89 stddev:13960.19 (31.0%) stddev-sans-first:13970.95 22:29:57 INFO - PROCESS | 17707 | Values: 661.7 700.7 723.1 729.5 747.1 10847.2 11006.2 11053.0 11054.7 11982.1 12068.1 12108.4 12176.4 12218.1 12288.3 636.7 764.1 793.7 794.3 794.6 795.1 796.8 798.3 799.6 800.0 842.9 851.7 852.3 852.9 853.0 662.8 820.9 844.9 845.9 846.6 619.4 619.8 620.1 620.4 621.2 11101.2 11152.3 11164.3 11166.4 11166.6 12221.5 12777.0 12800.1 13022.4 13194.4 9573.6 9576.6 9587.8 9698.0 9742.6 3361.9 3403.6 3438.5 3443.7 3446.2 44987.0 45018.0 45079.0 45110.8 45216.3 9872.5 10059.6 10213.2 10274.5 10344.6 18334.5 18364.1 18383.6 18391.0 18398.2 6536.3 6693.2 6785.7 6879.6 7034.0 3381.4 3428.5 3573.9 3693.7 3793.0 2430.9 2507.4 2514.5 2522.6 2526.5 436.5 492.7 499.5 528.5 559.3 478.1 478.9 547.4 563.1 568.8 7488.0 7732.3 7866.9 8075.1 8582.7 582.5 613.4 626.4 638.5 642.5 3865.6 3881.3 3883.7 3883.7 3999.5 45338.8 49392.5 49491.5 49618.0 49644.5 41123.8 43323.0 44703.1 45035.8 46009.5 39459.2 44430.3 44944.8 45982.3 46744.8 8029.4 8291.4 8374.8 8393.3 8661.5 27886.7 28308.6 28403.3 28424.6 29091.4 34418.0 34870.5 37902.4 38118.0 38329.2 11081.9 11142.3 11191.2 11358.9 11586.3 11859.4 11994.7 12201.6 12474.0 12599.2 10813.8 10937.3 10948.7 11644.2 11659.9 10168.5 10475.3 10575.7 10610.3 10856.0 10462.2 10817.8 11100.3 11102.7 11377.9 9276.9 9327.7 9512.5 9526.3 9673.7 9228.6 9585.6 9641.6 9717.0 9770.2 22:29:57 INFO - PROCESS | 17707 | -------- Summary: end -------- 22:29:57 INFO - PROCESS | 17707 | 22:29:57 INFO - TEST-INFO | 17707: exit 0 22:29:57 INFO - TEST-OK | dromaeo_css | took 1117859ms 22:29:57 INFO - TEST-START | kraken 22:29:57 INFO - operating with platform_type : linux_ 22:29:57 INFO - Initialising browser for kraken test... 22:29:57 INFO - TEST-INFO | started process 17890 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpan1MNF/profile http://localhost:37041/getInfo.html) 22:30:01 INFO - PROCESS | 17890 | __metrics Screen width/height:1600/1200 22:30:01 INFO - PROCESS | 17890 | colorDepth:24 22:30:01 INFO - PROCESS | 17890 | Browser inner width/height: 1024/697 22:30:01 INFO - PROCESS | 17890 | __metrics 22:30:03 ERROR - PROCESS | 17890 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 22:30:04 INFO - TEST-INFO | 17890: exit 0 22:30:04 INFO - Browser initialized. 22:30:04 INFO - Running cycle 1/1 for kraken test... 22:30:04 INFO - TEST-INFO | started process 17975 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpan1MNF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:30:16 INFO - PROCESS | 17975 | RSS: Main: 154636288 22:30:16 INFO - PROCESS | 17975 | 22:30:18 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:37041/tests/kraken/driver.html?testName=audio-beat-detection) 22:30:19 INFO - PROCESS | 17975 | RSS: Main: 209743872 22:30:19 INFO - PROCESS | 17975 | 22:30:22 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:37041/tests/kraken/driver.html?testName=audio-dft) 22:30:23 INFO - PROCESS | 17975 | RSS: Main: 195260416 22:30:23 INFO - PROCESS | 17975 | 22:30:26 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:37041/tests/kraken/driver.html?testName=audio-fft) 22:30:26 INFO - PROCESS | 17975 | RSS: Main: 266305536 22:30:26 INFO - PROCESS | 17975 | 22:30:29 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:37041/tests/kraken/driver.html?testName=audio-oscillator) 22:30:29 INFO - PROCESS | 17975 | RSS: Main: 173924352 22:30:29 INFO - PROCESS | 17975 | 22:30:31 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:37041/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:30:32 INFO - PROCESS | 17975 | RSS: Main: 178659328 22:30:32 INFO - PROCESS | 17975 | 22:30:35 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:37041/tests/kraken/driver.html?testName=imaging-darkroom) 22:30:36 INFO - PROCESS | 17975 | RSS: Main: 214298624 22:30:36 INFO - PROCESS | 17975 | 22:30:39 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:37041/tests/kraken/driver.html?testName=imaging-desaturate) 22:30:40 INFO - PROCESS | 17975 | RSS: Main: 215744512 22:30:40 INFO - PROCESS | 17975 | 22:30:43 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:37041/tests/kraken/driver.html?testName=json-parse-financial) 22:30:43 INFO - PROCESS | 17975 | RSS: Main: 216068096 22:30:43 INFO - PROCESS | 17975 | 22:30:45 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:37041/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:30:46 INFO - PROCESS | 17975 | RSS: Main: 201191424 22:30:46 INFO - PROCESS | 17975 | 22:30:47 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:30:48 INFO - PROCESS | 17975 | RSS: Main: 205041664 22:30:48 INFO - PROCESS | 17975 | 22:30:50 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:30:51 INFO - PROCESS | 17975 | RSS: Main: 218968064 22:30:51 INFO - PROCESS | 17975 | 22:30:54 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:30:54 INFO - PROCESS | 17975 | RSS: Main: 224550912 22:30:54 INFO - PROCESS | 17975 | 22:30:57 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:30:58 INFO - PROCESS | 17975 | RSS: Main: 217669632 22:30:58 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | Cycle 1(1): loaded http://localhost:37041/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:37041/tests/kraken/driver.html?testName=ai-astar) 22:31:00 INFO - PROCESS | 17975 | RSS: Main: 281133056 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | __start_tp_report 22:31:00 INFO - PROCESS | 17975 | _x_x_mozilla_page_load 22:31:00 INFO - PROCESS | 17975 | _x_x_mozilla_page_load_details 22:31:00 INFO - PROCESS | 17975 | |i|pagename|runs| 22:31:00 INFO - PROCESS | 17975 | |0;ai-astar;111;113;108;112;115;113;102;115;114;113 22:31:00 INFO - PROCESS | 17975 | |1;audio-beat-detection;161;141;158;190;132;166;157;132;183;132 22:31:00 INFO - PROCESS | 17975 | |2;audio-dft;161;162;160;162;164;162;161;161;164;161 22:31:00 INFO - PROCESS | 17975 | |3;audio-fft;75;74;108;104;74;75;72;122;100;74 22:31:00 INFO - PROCESS | 17975 | |4;audio-oscillator;95;95;142;95;94;93;150;94;93;148 22:31:00 INFO - PROCESS | 17975 | |5;imaging-gaussian-blur;117;117;117;118;117;117;117;117;117;118 22:31:00 INFO - PROCESS | 17975 | |6;imaging-darkroom;147;145;146;146;148;146;146;147;147;146 22:31:00 INFO - PROCESS | 17975 | |7;imaging-desaturate;95;95;95;96;94;95;95;95;95;94 22:31:00 INFO - PROCESS | 17975 | |8;json-parse-financial;64;64;64;66;65;65;66;78;62;63 22:31:00 INFO - PROCESS | 17975 | |9;json-stringify-tinderbox;65;66;66;66;65;65;64;65;65;65 22:31:00 INFO - PROCESS | 17975 | |10;stanford-crypto-aes;78;78;79;83;88;79;79;80;80;82 22:31:00 INFO - PROCESS | 17975 | |11;stanford-crypto-ccm;143;132;132;131;129;142;132;130;163;143 22:31:00 INFO - PROCESS | 17975 | |12;stanford-crypto-pbkdf2;176;175;171;174;180;175;176;178;175;178 22:31:00 INFO - PROCESS | 17975 | |13;stanford-crypto-sha256-iterative;73;73;72;71;71;72;73;72;70;72 22:31:00 INFO - PROCESS | 17975 | __end_tp_report 22:31:00 INFO - PROCESS | 17975 | __start_cc_report 22:31:00 INFO - PROCESS | 17975 | _x_x_mozilla_cycle_collect,1876 22:31:00 INFO - PROCESS | 17975 | __end_cc_report 22:31:00 INFO - PROCESS | 17975 | __startTimestamp1461043860368__endTimestamp 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | ------- Summary: start ------- 22:31:00 INFO - PROCESS | 17975 | Number of tests: 14 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#0] ai-astar Cycles:10 Average:111.60 Median:113.00 stddev:3.95 (3.5%) stddev-sans-first:4.18 22:31:00 INFO - PROCESS | 17975 | Values: 111.0 113.0 108.0 112.0 115.0 113.0 102.0 115.0 114.0 113.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#1] audio-beat-detection Cycles:10 Average:155.20 Median:159.50 stddev:20.98 (13.2%) stddev-sans-first:22.15 22:31:00 INFO - PROCESS | 17975 | Values: 161.0 141.0 158.0 190.0 132.0 166.0 157.0 132.0 183.0 132.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#2] audio-dft Cycles:10 Average:161.80 Median:162.00 stddev:1.32 (0.8%) stddev-sans-first:1.36 22:31:00 INFO - PROCESS | 17975 | Values: 161.0 162.0 160.0 162.0 164.0 162.0 161.0 161.0 164.0 161.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#3] audio-fft Cycles:10 Average:87.80 Median:74.00 stddev:18.67 (25.2%) stddev-sans-first:19.22 22:31:00 INFO - PROCESS | 17975 | Values: 75.0 74.0 108.0 104.0 74.0 75.0 72.0 122.0 100.0 74.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#4] audio-oscillator Cycles:10 Average:109.90 Median:94.00 stddev:25.46 (27.1%) stddev-sans-first:26.42 22:31:00 INFO - PROCESS | 17975 | Values: 95.0 95.0 142.0 95.0 94.0 93.0 150.0 94.0 93.0 148.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#5] imaging-gaussian-blur Cycles:10 Average:117.20 Median:117.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 22:31:00 INFO - PROCESS | 17975 | Values: 117.0 117.0 117.0 118.0 117.0 117.0 117.0 117.0 117.0 118.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#6] imaging-darkroom Cycles:10 Average:146.40 Median:146.50 stddev:0.84 (0.6%) stddev-sans-first:0.87 22:31:00 INFO - PROCESS | 17975 | Values: 147.0 145.0 146.0 146.0 148.0 146.0 146.0 147.0 147.0 146.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#7] imaging-desaturate Cycles:10 Average:94.90 Median:95.00 stddev:0.57 (0.6%) stddev-sans-first:0.60 22:31:00 INFO - PROCESS | 17975 | Values: 95.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 95.0 94.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#8] json-parse-financial Cycles:10 Average:65.70 Median:65.00 stddev:4.50 (6.9%) stddev-sans-first:4.73 22:31:00 INFO - PROCESS | 17975 | Values: 64.0 64.0 64.0 66.0 65.0 65.0 66.0 78.0 62.0 63.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#9] json-stringify-tinderbox Cycles:10 Average:65.20 Median:65.00 stddev:0.63 (1.0%) stddev-sans-first:0.67 22:31:00 INFO - PROCESS | 17975 | Values: 65.0 66.0 66.0 66.0 65.0 65.0 64.0 65.0 65.0 65.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#10] stanford-crypto-aes Cycles:10 Average:80.60 Median:80.00 stddev:3.06 (3.8%) stddev-sans-first:3.10 22:31:00 INFO - PROCESS | 17975 | Values: 78.0 78.0 79.0 83.0 88.0 79.0 79.0 80.0 80.0 82.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#11] stanford-crypto-ccm Cycles:10 Average:137.70 Median:137.00 stddev:10.50 (7.7%) stddev-sans-first:10.96 22:31:00 INFO - PROCESS | 17975 | Values: 143.0 132.0 132.0 131.0 129.0 142.0 132.0 130.0 163.0 143.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.80 Median:176.00 stddev:2.49 (1.4%) stddev-sans-first:2.64 22:31:00 INFO - PROCESS | 17975 | Values: 176.0 175.0 171.0 174.0 180.0 175.0 176.0 178.0 175.0 178.0 22:31:00 INFO - PROCESS | 17975 | 22:31:00 INFO - PROCESS | 17975 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:72.00 stddev:0.99 (1.4%) stddev-sans-first:0.97 22:31:00 INFO - PROCESS | 17975 | Values: 73.0 73.0 72.0 71.0 71.0 72.0 73.0 72.0 70.0 72.0 22:31:00 INFO - PROCESS | 17975 | -------- Summary: end -------- 22:31:00 INFO - PROCESS | 17975 | 22:31:01 INFO - TEST-INFO | 17975: exit 0 22:31:01 INFO - TEST-OK | kraken | took 63575ms 22:31:01 INFO - SUITE-END | took 1181s 22:31:01 INFO - Completed test suite (00:19:41) 22:31:01 INFO - javascript benchmark 22:31:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2598.4153565447778, 2659.8138130330885, 2677.076408639019, 2687.2744359023063, 2698.27146670399, 4522.366868638385, 4580.656450766193, 4580.908381832362, 4590.839320623779, 4602.171609110367, 4595.0, 4614.884627884305, 4634.745089020103, 4654.0, 4664.533546645333, 4223.61987421132, 4238.02525419153, 4264.616184543406, 4285.357196420547, 4391.626711729491, 4336.653067754588, 4339.891502712426, 4353.064091220387, 4385.802638881242, 4397.692161548693, 4141.088960428701, 4217.388478670576, 4243.130158317537, 4288.892777680568, 4315.3095504719095, 4288.935665965013, 4292.29177185764, 4295.312749959991, 4307.504636966758, 4336.436263285753, 4258.5528519505315, 4270.871873843789, 4276.657867370603, 4281.357796330544, 4321.438213032317, 4407.2507673695545, 4515.706479078849, 4517.706349087299, 4525.796339164745, 4595.6323494120725, 4860.076585448754, 4864.756762161913, 4901.166801643693, 4987.177115775893, 4992.976439829826, 4940.8764780880765, 4942.654014219006, 4951.03454826309, 4958.429780575244, 4960.156773348539, 4615.26155815068, 4620.4686461056735, 4621.0295837873755, 4622.884427889329, 4672.532746725335, 4883.560479556786, 5078.466760990117, 5131.923021154686, 5156.845294641092, 5183.222516622462, 4857.077155340474, 4858.514148585184, 4865.197242455026, 4865.635077369141, 4916.459189489154, 4822.445418776815, 4918.827841025547, 4920.188168952082, 4927.728974906343, 4962.851114466572, 4858.73276969763, 4869.245266983623, 4924.507549245047, 4941.0, 4943.110240156806, 4844.781984810692, 4868.41579010521, 4869.050535145683, 4876.975615121902, 4921.803127874917, 4013.3779264214095, 4101.343784994387, 4109.691773117028, 4110.486189226347, 4145.54399016108, 3614.6023937366867, 3614.945775813365, 3660.5607327120383, 3697.630236976335, 3729.21686445844, 4314.956850431518, 4324.567543245713, 4328.761918094535, 4345.044090300129, 4368.148211098805, 4767.785449654774, 4967.5777558907175, 4986.326845875833, 4987.501249874984, 4987.62592805541, 4238.682098842599, 4277.422547956048, 4292.914141717149, 4313.0, 4334.696571239983, 4452.9090372858855, 4477.955220447819, 4478.865634030984, 4492.842750503782, 4520.208963431386, 4603.562661547147, 4696.600788932979, 4746.169420351424, 4758.428988521399, 4759.809607615656, 5034.219695947134, 5039.949600503948, 5045.066662667418, 5046.318746969112, 5099.0, 4729.834455794031, 4784.138855005993, 4786.545278198566, 4789.928151077806, 4833.951660483491, 4769.379980602499, 4798.448178459522, 4872.731999740048, 4960.553550180501, 4982.950170498394, 4893.486183950777, 4905.975470122627, 4927.679700819435, 4966.379202599675, 4995.525425084611, 4821.083994041121, 4824.927626085681, 4828.7102773833685, 4829.517048295143, 4875.878103047452, 4677.532246775295, 4758.571728544448, 4826.131296366547, 4838.959623680926, 4847.442544107473, 4750.14497390473, 4756.548127927841, 4764.356811830358, 4773.188557945088, 4824.276358546246, 4841.540053694894, 4863.610911127031, 4866.80532778699, 4869.31829543857, 4916.311716359784, 4565.13262480141, 4642.675012749075, 4723.055388922161, 4756.33411322422, 4819.95180048195], "value": 4551.906574466965, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [575.4044563876389, 599.1073300781836, 606.4148097086312, 614.6895817612111, 622.7384498510627, 34199.31601367971, 35208.823955880245, 35347.64652353476, 35348.82325588375, 35359.11602209946, 37032.259354812886, 37875.43186852199, 37877.0, 37955.0, 37956.0, 7539.623018849063, 7728.845423091563, 7736.535807851519, 7739.148693643695, 7745.070591529025, 7653.387728981696, 7748.922510774876, 7765.029371328584, 7766.417518686093, 7773.0, 7744.457887947846, 7792.376609871225, 7807.804804879866, 7808.843823123534, 7813.726519571816, 7719.112302085276, 7776.377889768805, 7787.610619469004, 7789.10425301092, 7789.805254868617, 7568.848623027564, 7654.119776225694, 7666.540007599506, 7671.27122923327, 7676.577788221645, 36971.630283697086, 37166.0, 37482.62517374819, 37503.624963750284, 37508.624913750784, 3018.9396212075635, 3165.208697825544, 3349.129226401129, 3402.2855200407944, 3552.0942159749357, 27995.86002069997, 28149.85925070382, 28405.857970710014, 29065.418691626048, 29350.477942646805, 3125.6092988376454, 3171.8255495947947, 3194.952075718866, 3218.5333126696496, 3276.8033917965, 226233.0, 227026.0, 227658.0, 227961.0, 228752.0, 32661.51007734886, 33590.64865459394, 33636.32727345439, 50522.49477505178, 51224.74387628038, 30927.381452371275, 31377.215569610486, 31395.84302078475, 31446.0, 31446.371072578877, 13214.141080829715, 13404.865951340556, 13413.865861341457, 13421.328933553283, 13426.530071447452, 10905.073068789083, 10985.670429887115, 10997.945010274898, 11026.503807328689, 11027.17296202788, 8082.757517274491, 8094.595270236582, 8124.715634952749, 8157.959210203911, 8197.95901020503, 3146.7639927005107, 3203.1511649412923, 3206.278587317835, 3275.2958114005605, 3279.442494775894, 875.5272153037416, 883.1301168349171, 949.292776881228, 978.8972157923315, 981.3915372469115, 27605.585916211272, 27714.0, 27809.72190278071, 28096.85951570229, 28201.153965381072, 1011.261778901406, 1020.2246292817364, 1031.3863251365426, 1076.0046956565145, 1121.2487633285718, 14933.17867517297, 14982.40070397172, 15029.549113526611, 15154.696906062038, 15262.0, 320939.0, 328086.0, 328345.0, 329036.0, 329927.4087109562, 46452.0, 46981.765091174326, 47045.0, 47110.52889471062, 47240.5275947243, 1066.1737153705785, 1108.8835672254459, 1159.4318783796048, 1241.9068569857477, 1253.6489782860813, 26664.0, 27246.910123594833, 27356.726432735417, 27571.586426204023, 27935.860320698266, 37743.24513509659, 38139.42790858084, 38216.23567528578, 38257.23485530329, 38298.0, 212933.57730649307, 215221.0, 215239.0, 215342.0, 215398.0, 24886.25341239766, 38926.610733892296, 40077.198456030135, 40129.39805902856, 40392.0, 38617.61382386253, 39164.216715666094, 39644.207115858095, 39939.0, 40110.0, 33677.0, 33974.320513590086, 34291.82854085714, 34332.656673432946, 34555.13612159716, 33711.0, 34070.318593628486, 34143.65856341405, 34374.48438273478, 34663.480047798796, 34095.65904340925, 34302.82848585741, 34485.0, 34837.0, 34850.651493484744, 30287.242818929702, 30977.225569360948, 31126.895069834358, 31241.25395284, 31316.843415782776, 24228.515429690953, 24316.0, 24440.26679199627, 24460.0, 24669.753302466746], "value": 15959.430506579662, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.4976456665784, 379.1923203576382, 382.4454540915671, 383.56465411757654, 396.16804710108795, 15787.526374208777, 16142.919285403572, 17092.572685682862, 17108.657826843457, 17111.31554737809, 15575.0, 15760.211989400541, 16458.09480478573, 16492.83507164928, 16501.0, 4615.999999999992, 4747.501512341206, 4763.047390521892, 4771.332013518093, 4774.713517188962, 3395.864165433392, 5217.660852044624, 5532.529734972532, 5535.806246781363, 5539.0, 5506.366767821692, 5513.228148059275, 5518.972405137969, 5520.613557051008, 5536.944630553683, 4855.344528488644, 5204.42751297357, 5226.451222621646, 5230.0, 5231.73841307935, 3268.7548433867555, 3495.1786330212635, 3498.982505087484, 3499.265154317596, 4797.880052998703, 12025.0, 12277.508899644003, 12290.078244131726, 12892.0, 19751.407457776146, 1648.6785076910007, 1804.9007304598242, 1836.173721825184, 1918.213532451688, 1935.022813479198, 8650.26472749817, 8946.597403116875, 9101.908980910172, 9487.857682134774, 9595.376300540513, 1652.8512433880887, 1713.1776747161432, 1721.2426532325737, 1725.197783030884, 1763.612005358819, 68342.65828670874, 68772.0, 70064.0, 70719.0, 72479.27520724777, 10155.187584993331, 10157.288989770645, 10159.949200253952, 10300.948495257475, 10311.48442577868, 14289.714205715827, 14400.135991840523, 14411.207383594003, 14416.062955907832, 14436.422543098368, 7105.289471052956, 7244.239354867768, 7294.489385742947, 7430.777076687708, 7435.442341824385, 6711.530192886548, 6736.2590115087305, 6748.358905903931, 6803.0, 6820.727170913108, 4395.978020109879, 4413.646908247332, 4422.425084739028, 4458.219811532968, 4487.955120448753, 1751.211954620426, 1764.7441121037377, 1836.3205613922935, 1842.5854182808762, 1846.6306738652324, 748.6892939430076, 873.6025108575583, 874.7463235661587, 882.7925437522177, 885.2608072259735, 15570.455034073753, 15692.843071569367, 16057.67884642324, 17895.194716237802, 18333.3583324583, 882.4352414454754, 888.693400776731, 892.2193081054078, 902.278177458031, 947.4599478297305, 7253.56478611285, 9293.860592091123, 9594.520273986273, 9623.326367154232, 9799.11807937289, 284543.0, 291967.0, 292902.0, 398921.0, 407418.0, 78757.60621196857, 82774.0, 84426.57786711027, 85305.0, 85645.14354856496, 976.6581696406201, 978.7846673731767, 994.6518718448485, 1023.6468418395641, 1024.109025148126, 14699.033589510176, 15391.923040384727, 15683.372665093724, 15807.920960395124, 15949.68100638004, 33882.66117338795, 34209.82895085509, 34591.481127783605, 34680.826595866856, 34922.47616285809, 49757.50242497529, 50051.249231262285, 50201.24698129458, 50270.24594631157, 50460.49539504558, 6718.0, 7121.465890058163, 7311.159216690149, 7361.595112268877, 7388.224236455309, 10388.0, 11317.773644526898, 11488.0, 11696.90416910512, 11742.530298787953, 5335.146376579729, 8205.876911846446, 8953.149450802164, 10301.175905927657, 10642.308249963728, 10013.799724005625, 10115.848262276219, 10178.847317290394, 10325.690229293134, 10403.271770975958, 10532.631357902437, 10721.0, 10740.46297685097, 10757.30077544957, 10783.0, 7131.716291067658, 7173.0675012248075, 7189.928100719135, 7279.090113735783, 7328.78013659591, 9048.50233237152, 10114.696559103239, 10139.543720532742, 10527.842082368923, 10696.465176741287], "value": 7903.434410106492, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1256.8617452080275, 1448.9784701785243, 1481.0817293278167, 1510.9602037249715, 1594.186964648029, 6569.86860262795, 7428.554286742792, 7613.048368953874, 7645.235476452352, 7648.19693932136, 7373.631318434083, 7511.173770885198, 7519.097708275015, 7537.24627537246, 7552.773416797491, 1889.2726300374386, 2153.493928926709, 2219.456233222859, 2237.5413040326694, 2252.3468194223656, 2263.909443622253, 2292.9656055159185, 2296.9310920672324, 2300.1604414388794, 2300.52839167971, 2177.129148340661, 2245.247841972941, 2249.347689170147, 2252.5832720946596, 2256.1201131558705, 1861.9534511637182, 2175.878518444512, 2253.1663284584642, 2257.390504563775, 2260.525289689167, 2151.505153814616, 2174.402039439151, 2189.430748005514, 2191.0687957618106, 2211.9115235390564, 7585.051868516435, 7607.961960190164, 7649.311561959395, 7653.693852245903, 7656.655450504741, 4463.68754187207, 4608.5621865922685, 4629.120467111238, 4646.697964632322, 4692.929606055912, 2928.1947464447235, 2946.3076177098364, 2972.910812675623, 2991.0428662827685, 3005.1885990782366, 876.369014309696, 880.6521424037469, 888.9688860889838, 896.8475359188953, 912.5118061836952, 10386.480675966248, 10400.375977441377, 10403.739906502322, 10420.687379378554, 10450.111740502067, 4134.152498737752, 4236.3857240700545, 4360.106178233455, 4389.4732632083715, 4402.965303153756, 8853.0, 8949.642014319485, 8959.104089590988, 8962.73111806647, 8967.103289671111, 4089.3252613319064, 4769.213079841857, 4792.472827988919, 4797.760111994457, 4808.759562021885, 4202.768847713405, 4290.249206388869, 4318.222719910384, 4347.0, 4350.499692535335, 3624.731344029621, 3681.116532032293, 3721.2371463849845, 3771.4342848572933, 3803.9429408558894, 1202.188522747142, 1217.9878201217875, 1223.076577184238, 1238.6779437346174, 1241.1808206583792, 685.2736099734198, 765.2118318132221, 773.9729109481141, 788.5544667263093, 881.8148188880278, 5026.698398096126, 5130.4356520782685, 5165.147750621106, 5232.215167724872, 5263.631545791758, 836.8368168207141, 840.995795021021, 853.6670698427619, 884.6417201033591, 921.161742814036, 3600.7839529628304, 3618.2944325856733, 3636.3272794533095, 3638.4542318651884, 3718.8698395556567, 9249.630014799468, 9527.237820974306, 9570.138687518156, 9583.568739406743, 9699.70900872975, 9486.857697134548, 9588.66439674608, 9597.184239339595, 9606.375585586915, 9612.134907858186, 7170.888512280605, 7214.819629509304, 7352.485326027234, 7392.852142957218, 7418.1839997599145, 5208.869778255574, 5213.113770658922, 5222.529972302511, 5299.814506492255, 5398.433164517669, 8739.606717697592, 8765.91234087651, 8833.955830221064, 8835.293176545989, 8888.73333799987, 8570.957145214234, 8811.823763524566, 9049.909500904907, 9134.177923986874, 9162.083791620784, 6432.0, 6661.467082633475, 6678.365555272241, 6774.93225067743, 6850.862982740217, 7348.301911318416, 7379.889301660372, 7397.519161254501, 7403.925960740324, 7452.850942981002, 6486.805395838132, 6647.5014373921185, 6722.663866806738, 6762.0, 6766.120404347404, 6485.740570377132, 6528.967355163384, 6745.52781305304, 6793.966030169818, 6812.114425124702, 6244.125822384961, 6285.0, 6297.307296197323, 6309.936900630935, 6312.084747711464, 4685.648576356719, 4686.92969605463, 4689.882752931204, 4769.73766442849, 4788.856334309976, 4569.5887370135515, 4734.526547345238, 4840.10458065245, 4896.216605343128, 4960.330355402119], "value": 4136.573325450198, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.11609346101886, 298.3704383750287, 304.0771259228241, 308.5803307501799, 309.70887365876087, 4966.230234313678, 5001.049800537895, 5005.874853128669, 5008.599312055036, 5011.273365362021, 4926.408830940283, 4934.851954441372, 4941.876453088675, 4945.752712364385, 4953.207486802113, 3697.5562932448142, 3779.7543159694533, 3908.921821563567, 3946.842126314944, 3971.0, 3892.143728379752, 3934.527856657205, 3947.7828719420563, 3951.111000024986, 3966.2067586482676, 3662.2675464907124, 3888.1446081862086, 3910.5502867170353, 3916.647501724844, 3922.9803850980707, 3783.432485127239, 3894.3768996960353, 3903.199844031995, 3906.160175562268, 3907.1404291056065, 3445.500402441632, 3446.138465383654, 3458.581981387644, 3463.8960831175104, 3467.150548115728, 4761.262004389325, 4775.259834725588, 4786.712797232177, 4796.688215265996, 4802.279658051285, 4259.10558782656, 4426.7122637028815, 4471.5081341052455, 4478.8432404866, 4483.798229079699, 5174.43081261061, 5179.876250737218, 5180.274761533351, 5197.220416937452, 5214.582833373321, 3774.660280574734, 3792.222594368148, 3795.2599243147597, 3797.373433383488, 3801.239752049601, 5240.895182096375, 5398.082326004589, 5448.400675925645, 5448.536874365724, 5466.753996070186, 3861.5366156061455, 3880.20455806559, 3888.3972984187494, 3893.9415908761393, 3902.492675952108, 3921.6078392161126, 3924.4309575111447, 3937.9212415751526, 3950.1902110067367, 3955.7033222508426, 4443.577860103285, 4462.308342206963, 4464.361545340537, 4470.329450582439, 4471.7987690554, 4901.411830580353, 5174.663646862942, 5182.32629758129, 5188.22176673502, 5204.4535323791215, 1306.6276111308232, 1320.7094439223356, 1326.085001349066, 1331.2012792324529, 1332.826732524785, 827.5448503323269, 882.4528792148909, 887.627196577439, 898.7213963671152, 909.1771946388396, 4557.635389168925, 4566.817327306937, 4591.747453890068, 4594.5864872161665, 4597.287420449836, 5315.654482458705, 5319.7340132993195, 5333.359986806556, 5339.41266460689, 5344.839654810362, 4474.26174681174, 4563.0, 4566.771661416916, 4588.220002599566, 4599.264117741212, 2834.744872961402, 2835.2628316637824, 2858.285428642839, 2862.914112576626, 2863.2269287292315, 5008.098542262428, 5036.708297724526, 5043.0, 5046.142155833517, 5047.646664733434, 5233.973830130825, 5444.945550544444, 5446.992306423324, 5450.781968721286, 5457.83626491198, 4990.850274491771, 5035.924461133086, 5039.899202016012, 5043.798248070036, 5051.444341122474, 5310.097283461898, 5319.946800531945, 5333.12003519415, 5336.279602253801, 5339.73301334924, 5040.974795126001, 5208.192730126836, 5297.205419187153, 5301.787928482971, 5356.946430535645, 5148.330717006915, 5148.970205958898, 5166.664166829144, 5167.896642067213, 5168.715720635401, 3738.5513738351574, 3764.7741135531955, 3776.773393596393, 3783.2055268393947, 3784.3566593679693, 5198.402183748917, 5373.247745315737, 5390.946090539044, 5398.460153984727, 5410.458954104558, 5179.533841954091, 5209.973950130225, 5228.607854410859, 5244.082285600081, 5256.0, 5215.374155101412, 5230.790768369375, 5236.109861323508, 5245.921311180259, 5247.895042099213, 4949.7030178189425, 5120.18077107661, 5173.439147621952, 5175.223716442413, 5189.714565698923, 5040.924386134137, 5114.948850511447, 5268.631195816256, 5296.947030529645, 5301.973490132525, 5135.537801597725, 5148.794048238028, 5167.560757335668, 5171.172612382001, 5177.145770947752], "value": 3933.6269917687955, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [661.7253839656543, 700.7337211859492, 723.1358526560758, 729.5440349781387, 747.1068164838483, 10847.186461015415, 11006.229563930508, 11053.0, 11054.723631909206, 11982.101342399312, 12068.094892883026, 12108.394580270995, 12176.391180440987, 12218.083643726712, 12288.262704237752, 636.707114727226, 764.1250767870785, 793.714262865368, 794.3287921706149, 794.5510786405677, 795.1174196641723, 796.7689370082669, 798.309462315099, 799.6321692021648, 800.0479429478902, 842.8904242448473, 851.7317045130795, 852.3224036890656, 852.8720691896203, 852.9829403411928, 662.7845950066223, 820.8604537228686, 844.9366297527679, 845.9365547583894, 846.5936350551739, 619.3558698953128, 619.8202521268827, 620.1163342237294, 620.388916916841, 621.155228888711, 11101.222914395996, 11152.275102118336, 11164.274322169033, 11166.441677916071, 11166.609168679139, 12221.511139554406, 12777.0, 12800.103992720513, 13022.413991370411, 13194.406251718696, 9573.569189386495, 9576.605848537865, 9587.760305992337, 9698.0, 9742.610295588169, 3361.882334118319, 3403.5745531808525, 3438.5014172945034, 3443.6556344365363, 3446.1556918554866, 44987.0, 45017.99999999967, 45079.0, 45110.774446127885, 45216.321755173696, 9872.457014864107, 10059.647912323033, 10213.182945364353, 10274.486275686186, 10344.586216551405, 18334.541636459195, 18364.08179591015, 18383.632327353378, 18391.0, 18398.17208225633, 6536.346365363425, 6693.196816382066, 6785.728570857111, 6879.6216208108035, 7033.964830175816, 3381.425157723193, 3428.5200071989943, 3573.910652233715, 3693.7045036397026, 3792.9569368423354, 2430.927072187837, 2507.360623041113, 2514.4719608881974, 2522.608995605684, 2526.5325914705472, 436.46968932746233, 492.6772963708776, 499.53293670418276, 528.4873672537632, 559.30645998961, 478.1393491714899, 478.9137955168103, 547.4334064243488, 563.0962305499696, 568.7867049856304, 7487.962560187164, 7732.342750866127, 7866.881996770053, 8075.071366792777, 8582.656693732306, 582.4662717180316, 613.3835495327168, 626.3673689573473, 638.5466318913624, 642.5277421095491, 3865.5747867734526, 3881.340172170738, 3883.650471457582, 3883.747556408881, 3999.5200575931076, 45338.773306133255, 49392.50607493951, 49491.50508494941, 49618.0, 49644.50355496399, 41123.7943810279, 43323.0, 44703.10593788106, 45035.77482112634, 46009.539904600526, 39459.210815783525, 44430.33354499685, 44944.77527612341, 45982.310265346045, 46744.766276168404, 8029.397795165269, 8291.419600627898, 8374.83250335002, 8393.286570641563, 8661.480311181349, 27886.72113278841, 28308.575371369036, 28403.289917752223, 28424.57363139513, 29091.418171636873, 34418.0, 34870.476942846384, 37902.43146352862, 38118.0, 38329.23341533098, 11081.944590277319, 11142.325841798072, 11191.216614836883, 11358.94320528392, 11586.304821710724, 11859.40702964831, 11994.700132496757, 12201.633950981484, 12474.0, 12599.18105323151, 10813.837792433276, 10937.343759374464, 10948.671539853816, 11644.2431241969, 11659.941700291443, 10168.491575421347, 10475.319104258198, 10575.653158296878, 10610.257281990187, 10856.0, 10462.163026957978, 10817.837732434176, 11100.333979961228, 11102.666919992413, 11377.943110284727, 9276.860847087433, 9327.720168394959, 9512.476813775309, 9526.285528585246, 9673.661421850202, 9228.584713687766, 9585.568649410932, 9641.614335426504, 9716.951415242878, 9770.169535589555], "value": 5849.311364458708, "unit": "score"}], "name": "dromaeo_css", "value": 6160.295880963263, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 108.0, 112.0, 115.0, 113.0, 102.0, 115.0, 114.0, 113.0], "value": 111.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 141.0, 158.0, 190.0, 132.0, 166.0, 157.0, 132.0, 183.0, 132.0], "value": 155.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 162.0, 160.0, 162.0, 164.0, 162.0, 161.0, 161.0, 164.0, 161.0], "value": 161.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 74.0, 108.0, 104.0, 74.0, 75.0, 72.0, 122.0, 100.0, 74.0], "value": 87.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 142.0, 95.0, 94.0, 93.0, 150.0, 94.0, 93.0, 148.0], "value": 109.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 117.0, 117.0, 118.0, 117.0, 117.0, 117.0, 117.0, 117.0, 118.0], "value": 117.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 145.0, 146.0, 146.0, 148.0, 146.0, 146.0, 147.0, 147.0, 146.0], "value": 146.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 95.0, 94.0], "value": 94.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 64.0, 66.0, 65.0, 65.0, 66.0, 78.0, 62.0, 63.0], "value": 65.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 66.0, 66.0, 66.0, 65.0, 65.0, 64.0, 65.0, 65.0, 65.0], "value": 65.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 78.0, 79.0, 83.0, 88.0, 79.0, 79.0, 80.0, 80.0, 82.0], "value": 80.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 132.0, 132.0, 131.0, 129.0, 142.0, 132.0, 130.0, 163.0, 143.0], "value": 137.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 175.0, 171.0, 174.0, 180.0, 175.0, 176.0, 178.0, 175.0, 178.0], "value": 175.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 73.0, 72.0, 71.0, 71.0, 72.0, 73.0, 72.0, 70.0, 72.0], "value": 71.9, "unit": "score"}], "name": "kraken", "value": 1581.7, "alertThreshold": 2.0}]} 22:31:01 INFO - Return code: 0 22:31:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 22:31:01 ERROR - # TBPL SUCCESS # 22:31:01 INFO - Running post-action listener: _resource_record_post_action 22:31:01 INFO - Running post-run listener: _resource_record_post_run 22:31:02 INFO - Total resource usage - Wall time: 1198s; CPU: 13.0%; Read bytes: 8192; Write bytes: 517758976; Read time: 32; Write time: 551732 22:31:02 INFO - install - Wall time: 16s; CPU: 14.0%; Read bytes: 0; Write bytes: 233811968; Read time: 0; Write time: 276744 22:31:02 INFO - run-tests - Wall time: 1183s; CPU: 13.0%; Read bytes: 8192; Write bytes: 283774976; Read time: 32; Write time: 274964 22:31:02 INFO - Running post-run listener: _upload_blobber_files 22:31:02 INFO - Blob upload gear active. 22:31:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:31:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 22:31:02 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 22:31:02 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 22:31:03 INFO - (blobuploader) - INFO - Open directory for files ... 22:31:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:31:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:04 INFO - (blobuploader) - INFO - Done attempting. 22:31:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:31:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:31:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:31:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:31:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:31:04 INFO - (blobuploader) - INFO - Done attempting. 22:31:04 INFO - (blobuploader) - INFO - Iteration through files over. 22:31:04 INFO - Return code: 0 22:31:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:31:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:31:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e95af87c2ca7c485e432e731c80d8ee83839f14a0fb56ecfc6af9d7b438e074c90d89ef11cf58640fe68b23b5c72f6f7295e7616dbe8885b97d817f824922af0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badd1eae2b4a542922c672cf0ab40f541ccbc2666d3facd5e3a078273d5fe819cbb40a49a67a749ccfcd72c0f02b575cf008591e7fe9252daa5efbd8a999629d"} 22:31:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:31:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:31:04 INFO - Contents: 22:31:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e95af87c2ca7c485e432e731c80d8ee83839f14a0fb56ecfc6af9d7b438e074c90d89ef11cf58640fe68b23b5c72f6f7295e7616dbe8885b97d817f824922af0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badd1eae2b4a542922c672cf0ab40f541ccbc2666d3facd5e3a078273d5fe819cbb40a49a67a749ccfcd72c0f02b575cf008591e7fe9252daa5efbd8a999629d"} 22:31:04 INFO - Running post-run listener: copy_logs_to_upload_dir 22:31:04 INFO - Copying logs to upload dir... 22:31:04 INFO - mkdir: /builds/slave/test/build/upload/logs 22:31:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1245.655959 ========= master_lag: 0.02 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 45 secs) (at 2016-04-18 22:31:03.930602) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 22:31:03.934699) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e95af87c2ca7c485e432e731c80d8ee83839f14a0fb56ecfc6af9d7b438e074c90d89ef11cf58640fe68b23b5c72f6f7295e7616dbe8885b97d817f824922af0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badd1eae2b4a542922c672cf0ab40f541ccbc2666d3facd5e3a078273d5fe819cbb40a49a67a749ccfcd72c0f02b575cf008591e7fe9252daa5efbd8a999629d"} build_url:https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2 program finished with exit code 0 elapsedTime=0.011463 build_url: 'https://queue.taskcluster.net/v1/task/SgxbkAsBTd2D8cnBwXFn9A/artifacts/public/build/firefox-48.0a1.en-US.linux-x86_64.tar.bz2' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/e95af87c2ca7c485e432e731c80d8ee83839f14a0fb56ecfc6af9d7b438e074c90d89ef11cf58640fe68b23b5c72f6f7295e7616dbe8885b97d817f824922af0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/badd1eae2b4a542922c672cf0ab40f541ccbc2666d3facd5e3a078273d5fe819cbb40a49a67a749ccfcd72c0f02b575cf008591e7fe9252daa5efbd8a999629d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-18 22:31:03.966322) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:31:03.966725) ========= 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=8c6b6742ce8f6f39ef4ce27d000001f1-1461038984.578870-176918948 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004457 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-18 22:31:04.051471) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 22:31:04.052017) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-18 22:31:04.052578) ========= ========= Total master_lag: 0.22 =========