builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0110 starttime: 1461791795.27 results: success (0) buildid: 20160427132147 builduid: af8cbe5b872147b9854b3a133c36c2eb revision: f1ff46a78fa5f30b208b33269b48ba4ba367b34f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.268653) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.269188) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.269485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.290054) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.290492) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.psVdi4XHuV/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-04-27 14:16:35-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 20.5M=0.001s 2016-04-27 14:16:35 (20.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.130612 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.438699) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.439017) ========= rm -rf scripts properties in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-rf', 'scripts', 'properties'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.psVdi4XHuV/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.030524 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.488495) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:35.488846) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f1ff46a78fa5f30b208b33269b48ba4ba367b34f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev f1ff46a78fa5f30b208b33269b48ba4ba367b34f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.psVdi4XHuV/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-04-27 14:16:35,560 truncating revision to first 12 chars 2016-04-27 14:16:35,560 Setting DEBUG logging. 2016-04-27 14:16:35,560 attempt 1/10 2016-04-27 14:16:35,560 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f1ff46a78fa5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 14:16:36,145 unpacking tar archive at: fx-team-f1ff46a78fa5/testing/mozharness/ program finished with exit code 0 elapsedTime=0.831071 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:36.336336) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:36.336659) ========= script_repo_revision: f1ff46a78fa5f30b208b33269b48ba4ba367b34f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:36.337036) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:36.337323) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 14:16:36.350745) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 26 mins, 45 secs) (at 2016-04-27 14:16:36.351053) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.psVdi4XHuV/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PROPERTIES_FILE=/builds/slave/test/buildprops.json PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:16:36 INFO - MultiFileLogger online at 20160427 14:16:36 in /builds/slave/test 14:16:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 14:16:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:16:36 INFO - {'append_to_log': False, 14:16:36 INFO - 'base_work_dir': '/builds/slave/test', 14:16:36 INFO - 'blob_upload_branch': 'Fx-Team', 14:16:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:16:36 INFO - 'branch': 'Fx-Team', 14:16:36 INFO - 'buildbot_json_path': 'buildprops.json', 14:16:36 INFO - 'config_files': ('talos/mac_config.py',), 14:16:36 INFO - 'default_actions': ('clobber', 14:16:36 INFO - 'read-buildbot-config', 14:16:36 INFO - 'download-and-extract', 14:16:36 INFO - 'populate-webroot', 14:16:36 INFO - 'create-virtualenv', 14:16:36 INFO - 'install', 14:16:36 INFO - 'run-tests'), 14:16:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:16:36 INFO - 'download_minidump_stackwalk': True, 14:16:36 INFO - 'download_symbols': 'ondemand', 14:16:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:16:36 INFO - 'tooltool.py': '/tools/tooltool.py', 14:16:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:16:36 INFO - '/tools/misc-python/virtualenv.py')}, 14:16:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:16:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:16:36 INFO - 'installer_path': 'installer.exe', 14:16:36 INFO - 'log_level': 'info', 14:16:36 INFO - 'log_name': 'talos', 14:16:36 INFO - 'log_to_console': True, 14:16:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:16:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:16:36 INFO - 'opt_config_files': (), 14:16:36 INFO - 'pip_index': False, 14:16:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:16:36 INFO - 'cmd': ('bash', 14:16:36 INFO - '-c', 14:16:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:16:36 INFO - 'enabled': True, 14:16:36 INFO - 'halt_on_failure': False, 14:16:36 INFO - 'name': 'check_screen_resolution'},), 14:16:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:16:36 INFO - 'cmd': ('bash', 14:16:36 INFO - '-c', 14:16:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:16:36 INFO - 'enabled': True, 14:16:36 INFO - 'halt_on_failure': False, 14:16:36 INFO - 'name': 'check_screen_resolution'},), 14:16:36 INFO - 'run_cmd_checks_enabled': True, 14:16:36 INFO - 'sps_profile': False, 14:16:36 INFO - 'sps_profile_interval': 0, 14:16:36 INFO - 'suite': 'dromaeojs', 14:16:36 INFO - 'system_bits': '32', 14:16:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:16:36 INFO - 'title': 't-yosemite-r7-0110', 14:16:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:16:36 INFO - 'use_talos_json': True, 14:16:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:16:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:16:36 INFO - 'work_dir': 'build'} 14:16:36 INFO - ##### 14:16:36 INFO - ##### Running clobber step. 14:16:36 INFO - ##### 14:16:36 INFO - Running pre-action listener: _resource_record_pre_action 14:16:36 INFO - Running main action method: clobber 14:16:36 INFO - rmtree: /builds/slave/test/build 14:16:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:16:38 INFO - Running post-action listener: _resource_record_post_action 14:16:38 INFO - ##### 14:16:38 INFO - ##### Running read-buildbot-config step. 14:16:38 INFO - ##### 14:16:38 INFO - Running pre-action listener: _resource_record_pre_action 14:16:38 INFO - Running main action method: read_buildbot_config 14:16:38 INFO - Using buildbot properties: 14:16:38 INFO - { 14:16:38 INFO - "project": "", 14:16:38 INFO - "product": "firefox", 14:16:38 INFO - "script_repo_revision": "production", 14:16:38 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 14:16:38 INFO - "repository": "", 14:16:38 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 14:16:38 INFO - "buildid": "20160427132147", 14:16:38 INFO - "pgo_build": "False", 14:16:38 INFO - "basedir": "/builds/slave/test", 14:16:38 INFO - "buildnumber": 731, 14:16:38 INFO - "slavename": "t-yosemite-r7-0110", 14:16:38 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 14:16:38 INFO - "platform": "yosemite_r7", 14:16:38 INFO - "scheduler": "tests-fx-team-macosx64-talos", 14:16:38 INFO - "branch": "fx-team", 14:16:38 INFO - "repo_path": "integration/fx-team", 14:16:38 INFO - "revision": "f1ff46a78fa5f30b208b33269b48ba4ba367b34f", 14:16:38 INFO - "stage_platform": "macosx64", 14:16:38 INFO - "builduid": "af8cbe5b872147b9854b3a133c36c2eb", 14:16:38 INFO - "slavebuilddir": "test" 14:16:38 INFO - } 14:16:38 INFO - Found installer url https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg. 14:16:38 INFO - Running post-action listener: _resource_record_post_action 14:16:38 INFO - ##### 14:16:38 INFO - ##### Running download-and-extract step. 14:16:38 INFO - ##### 14:16:38 INFO - Running pre-action listener: _resource_record_pre_action 14:16:38 INFO - Running main action method: download_and_extract 14:16:38 INFO - mkdir: /builds/slave/test/build/tests 14:16:38 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')]} 14:16:38 INFO - https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 14:16:38 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:16:38 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json 14:16:38 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:16:38 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json'}, attempt #1 14:16:39 INFO - Downloaded 1199 bytes. 14:16:39 INFO - Reading from file /builds/slave/test/build/firefox-49.0a1.en-US.mac.test_packages.json 14:16:39 INFO - Using the following test package requirements: 14:16:39 INFO - {u'common': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:16:39 INFO - u'cppunittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.cppunittest.tests.zip'], 14:16:39 INFO - u'gtest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.gtest.tests.zip'], 14:16:39 INFO - u'jittest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'jsshell-mac.zip'], 14:16:39 INFO - u'mochitest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.mochitest.tests.zip'], 14:16:39 INFO - u'mozbase': [u'firefox-49.0a1.en-US.mac.common.tests.zip'], 14:16:39 INFO - u'reftest': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.reftest.tests.zip'], 14:16:39 INFO - u'talos': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.talos.tests.zip'], 14:16:39 INFO - u'web-platform': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.web-platform.tests.zip'], 14:16:39 INFO - u'xpcshell': [u'firefox-49.0a1.en-US.mac.common.tests.zip', 14:16:39 INFO - u'firefox-49.0a1.en-US.mac.xpcshell.tests.zip']} 14:16:39 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:16:39 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')]} 14:16:39 INFO - https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:16:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:16:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:16:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:16:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:16:40 INFO - Downloaded 18117662 bytes. 14:16:40 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:16:40 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:16:43 INFO - Return code: 0 14:16:43 INFO - Downloading packages: [u'firefox-49.0a1.en-US.mac.common.tests.zip', u'firefox-49.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:16:43 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')]} 14:16:43 INFO - https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 14:16:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:16:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:16:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip 14:16:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:22:23 INFO - Downloaded 18117662 bytes. 14:22:23 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:22:23 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 14:22:27 INFO - Return code: 0 14:22:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:27 INFO - https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 14:22:27 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:22:27 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:22:27 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip 14:22:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:22:29 INFO - Downloaded 10840145 bytes. 14:22:29 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 14:22:29 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-49.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 14:22:29 INFO - Return code: 0 14:22:29 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')]} 14:22:29 INFO - https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 14:22:29 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:22:29 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:22:29 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg 14:22:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg'}, attempt #1 14:22:32 INFO - Downloaded 110190860 bytes. 14:22:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:22:32 INFO - mkdir: /builds/slave/test/properties 14:22:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:22:32 INFO - Writing to file /builds/slave/test/properties/build_url 14:22:32 INFO - Contents: 14:22:32 INFO - build_url:https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg 14:22:34 INFO - Running post-action listener: _resource_record_post_action 14:22:34 INFO - Running post-action listener: set_extra_try_arguments 14:22:34 INFO - ##### 14:22:34 INFO - ##### Running populate-webroot step. 14:22:34 INFO - ##### 14:22:34 INFO - Running pre-action listener: _resource_record_pre_action 14:22:34 INFO - Running main action method: populate_webroot 14:22:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:22:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:22:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:22:34 INFO - '--tppagecycles', 14:22:34 INFO - '7'], 14:22:34 INFO - 'tests': ['tsvgm']}}, 14:22:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:22:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:22:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:22:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:22:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['damp', 'tps']}, 14:22:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'tests': ['damp', 'tps']}, 14:22:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['dromaeo_dom']}, 14:22:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:22:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore', 14:22:34 INFO - 'tabpaint']}, 14:22:34 INFO - 'other-e10s': {'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore', 14:22:34 INFO - 'tabpaint']}, 14:22:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore', 14:22:34 INFO - 'tabpaint']}, 14:22:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore', 14:22:34 INFO - 'tabpaint']}, 14:22:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore', 14:22:34 INFO - 'tabpaint']}, 14:22:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['a11yr', 14:22:34 INFO - 'ts_paint', 14:22:34 INFO - 'tpaint', 14:22:34 INFO - 'sessionrestore', 14:22:34 INFO - 'sessionrestore_no_auto_restore', 14:22:34 INFO - 'tabpaint']}, 14:22:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['tsvgx', 14:22:34 INFO - 'tsvgr_opacity', 14:22:34 INFO - 'tart', 14:22:34 INFO - 'tscrollx', 14:22:34 INFO - 'cart']}, 14:22:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:22:34 INFO - 'tsvgr_opacity', 14:22:34 INFO - 'tart', 14:22:34 INFO - 'tscrollx', 14:22:34 INFO - 'cart']}, 14:22:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--disable-e10s'], 14:22:34 INFO - 'tests': ['tp5o']}, 14:22:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'tests': ['tp5o']}, 14:22:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--disable-e10s', 14:22:34 INFO - '--xperf_path', 14:22:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:34 INFO - 'tests': ['tp5n']}, 14:22:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:34 INFO - 'talos_options': ['--xperf_path', 14:22:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:34 INFO - 'tests': ['tp5n']}}, 14:22:34 INFO - 'talos.zip': {'path': '', 14:22:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:22:34 INFO - Running post-action listener: _resource_record_post_action 14:22:34 INFO - ##### 14:22:34 INFO - ##### Running create-virtualenv step. 14:22:34 INFO - ##### 14:22:34 INFO - Running pre-action listener: _resource_record_pre_action 14:22:34 INFO - Running main action method: create_virtualenv 14:22:34 INFO - Creating virtualenv /builds/slave/test/build/venv 14:22:34 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 14:22:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:22:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:22:34 INFO - Using real prefix '/tools/python27' 14:22:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:22:35 INFO - Installing distribute.............................................................................................................................................................................................done. 14:22:37 INFO - Installing pip.................done. 14:22:37 INFO - Return code: 0 14:22:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:22:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:22:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:37 INFO - 'HOME': '/Users/cltbld', 14:22:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:37 INFO - 'LOGNAME': 'cltbld', 14:22:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:37 INFO - 'MOZ_NO_REMOTE': '1', 14:22:37 INFO - 'NO_EM_RESTART': '1', 14:22:37 INFO - 'PAGER': '/bin/cat', 14:22:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:37 INFO - 'PWD': '/builds/slave/test', 14:22:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:37 INFO - 'SHELL': '/bin/bash', 14:22:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:22:37 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:22:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:37 INFO - 'USER': 'cltbld', 14:22:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:37 INFO - 'XPC_FLAGS': '0x0', 14:22:37 INFO - 'XPC_SERVICE_NAME': '0', 14:22:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:38 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 14:22:38 INFO - Cleaning up... 14:22:38 INFO - Return code: 0 14:22:38 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 14:22:38 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')]} 14:22:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:38 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')]} 14:22:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:38 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:38 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 14:22:38 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 14:22:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:22:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:38 INFO - 'HOME': '/Users/cltbld', 14:22:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:38 INFO - 'LOGNAME': 'cltbld', 14:22:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:38 INFO - 'MOZ_NO_REMOTE': '1', 14:22:38 INFO - 'NO_EM_RESTART': '1', 14:22:38 INFO - 'PAGER': '/bin/cat', 14:22:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:38 INFO - 'PWD': '/builds/slave/test', 14:22:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:38 INFO - 'SHELL': '/bin/bash', 14:22:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:22:38 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:22:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:38 INFO - 'USER': 'cltbld', 14:22:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:38 INFO - 'XPC_FLAGS': '0x0', 14:22:38 INFO - 'XPC_SERVICE_NAME': '0', 14:22:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:38 INFO - Downloading/unpacking psutil>=0.7.1 14:22:38 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 14:22:38 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 14:22:38 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 14:22:38 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 14:22:38 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 14:22:38 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 14:22:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:22:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:41 INFO - Installing collected packages: psutil 14:22:41 INFO - Running setup.py install for psutil 14:22:41 INFO - building 'psutil._psutil_osx' extension 14:22:41 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:22:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:22:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:22:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:22:42 INFO - building 'psutil._psutil_posix' extension 14:22:42 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:22:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:22:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:22:42 INFO - ^ 14:22:42 INFO - 1 warning generated. 14:22:42 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:22:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:42 INFO - Successfully installed psutil 14:22:42 INFO - Cleaning up... 14:22:42 INFO - Return code: 0 14:22:42 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:42 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:42 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 14:22:42 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 14:22:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:22:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:42 INFO - 'HOME': '/Users/cltbld', 14:22:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:42 INFO - 'LOGNAME': 'cltbld', 14:22:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:42 INFO - 'MOZ_NO_REMOTE': '1', 14:22:42 INFO - 'NO_EM_RESTART': '1', 14:22:42 INFO - 'PAGER': '/bin/cat', 14:22:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:42 INFO - 'PWD': '/builds/slave/test', 14:22:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:42 INFO - 'SHELL': '/bin/bash', 14:22:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:22:42 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:22:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:42 INFO - 'USER': 'cltbld', 14:22:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:42 INFO - 'XPC_FLAGS': '0x0', 14:22:42 INFO - 'XPC_SERVICE_NAME': '0', 14:22:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:42 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 14:22:42 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 14:22:42 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 14:22:42 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 14:22:42 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 14:22:42 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 14:22:42 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 14:22:45 INFO - Downloading mozsystemmonitor-0.0.tar.gz 14:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:22:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 14:22:45 INFO - Installing collected packages: mozsystemmonitor 14:22:45 INFO - Running setup.py install for mozsystemmonitor 14:22:45 INFO - Successfully installed mozsystemmonitor 14:22:45 INFO - Cleaning up... 14:22:45 INFO - Return code: 0 14:22:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:22:45 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')]} 14:22:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:45 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')]} 14:22:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:45 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:45 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 14:22:45 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 14:22:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:22:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:45 INFO - 'HOME': '/Users/cltbld', 14:22:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:45 INFO - 'LOGNAME': 'cltbld', 14:22:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:45 INFO - 'MOZ_NO_REMOTE': '1', 14:22:45 INFO - 'NO_EM_RESTART': '1', 14:22:45 INFO - 'PAGER': '/bin/cat', 14:22:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:45 INFO - 'PWD': '/builds/slave/test', 14:22:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:45 INFO - 'SHELL': '/bin/bash', 14:22:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:22:45 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:22:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:45 INFO - 'USER': 'cltbld', 14:22:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:45 INFO - 'XPC_FLAGS': '0x0', 14:22:45 INFO - 'XPC_SERVICE_NAME': '0', 14:22:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:45 INFO - Downloading/unpacking blobuploader==1.2.4 14:22:45 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 14:22:45 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 14:22:45 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 14:22:45 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 14:22:45 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 14:22:45 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 14:22:48 INFO - Downloading blobuploader-1.2.4.tar.gz 14:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:22:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:22:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 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 14:22:49 INFO - Downloading docopt-0.6.1.tar.gz 14:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:49 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:49 INFO - Running setup.py install for blobuploader 14:22:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:22:49 INFO - Running setup.py install for requests 14:22:50 INFO - Running setup.py install for docopt 14:22:50 INFO - Successfully installed blobuploader requests docopt 14:22:50 INFO - Cleaning up... 14:22:50 INFO - Return code: 0 14:22:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:50 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')]} 14:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:50 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')]} 14:22:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:50 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:50 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 14:22:50 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 14:22:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:22:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:50 INFO - 'HOME': '/Users/cltbld', 14:22:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:50 INFO - 'LOGNAME': 'cltbld', 14:22:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:50 INFO - 'MOZ_NO_REMOTE': '1', 14:22:50 INFO - 'NO_EM_RESTART': '1', 14:22:50 INFO - 'PAGER': '/bin/cat', 14:22:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:50 INFO - 'PWD': '/builds/slave/test', 14:22:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:50 INFO - 'SHELL': '/bin/bash', 14:22:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:22:50 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:22:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:50 INFO - 'USER': 'cltbld', 14:22:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:50 INFO - 'XPC_FLAGS': '0x0', 14:22:50 INFO - 'XPC_SERVICE_NAME': '0', 14:22:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:50 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-iLcDoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:50 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-1dq4Cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-44USxe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-aLcM_p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-VHJXCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-pLArdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ubnixf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-6OZjnR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0E3Y3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-UKCCSb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-h2HHTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:51 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-ICvO2S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-IIIFkp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-YE7ViL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-4ilqZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BoP9RD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:52 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-sqj8G8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:22:52 INFO - Running setup.py install for manifestparser 14:22:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:22:52 INFO - Running setup.py install for mozcrash 14:22:52 INFO - Running setup.py install for mozdebug 14:22:52 INFO - Running setup.py install for mozdevice 14:22:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Running setup.py install for mozfile 14:22:53 INFO - Running setup.py install for mozhttpd 14:22:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Running setup.py install for mozinfo 14:22:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Running setup.py install for mozInstall 14:22:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:22:53 INFO - Running setup.py install for mozleak 14:22:53 INFO - Running setup.py install for mozlog 14:22:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:22:54 INFO - Running setup.py install for moznetwork 14:22:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:22:54 INFO - Running setup.py install for mozprocess 14:22:54 INFO - Running setup.py install for mozprofile 14:22:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:22:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:22:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:22:54 INFO - Running setup.py install for mozrunner 14:22:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:22:54 INFO - Running setup.py install for mozscreenshot 14:22:54 INFO - Running setup.py install for moztest 14:22:54 INFO - Running setup.py install for mozversion 14:22:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:22:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:22:55 INFO - Cleaning up... 14:22:55 INFO - Return code: 0 14:22:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:55 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')]} 14:22:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:55 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')]} 14:22:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:55 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/config', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:55 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 14:22:55 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 14:22:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:22:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:55 INFO - 'HOME': '/Users/cltbld', 14:22:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:55 INFO - 'LOGNAME': 'cltbld', 14:22:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:55 INFO - 'MOZ_NO_REMOTE': '1', 14:22:55 INFO - 'NO_EM_RESTART': '1', 14:22:55 INFO - 'PAGER': '/bin/cat', 14:22:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:55 INFO - 'PWD': '/builds/slave/test', 14:22:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:55 INFO - 'SHELL': '/bin/bash', 14:22:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:22:55 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:22:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:55 INFO - 'USER': 'cltbld', 14:22:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:55 INFO - 'XPC_FLAGS': '0x0', 14:22:55 INFO - 'XPC_SERVICE_NAME': '0', 14:22:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-wbPqdR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:55 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)) 14:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-C3RRRZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:55 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)) 14:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-V8ehJc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:55 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)) 14:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-gmtvg7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:55 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)) 14:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-eP_P8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:55 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)) 14:22:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:55 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-gkWrZp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-k13rPN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-dinfIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-KZH5BS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-v2mQ16-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-GBdYdH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-OpKW91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-0Ushsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-06bXla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-uAuVwJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:22:56 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)) 14:22:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:22:56 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-QLUE0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:22:57 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)) 14:22:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:22:57 INFO - Running setup.py (path:/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/pip-BCV0L9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:22:57 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)) 14:22:57 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)) 14:22:57 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)) 14:22:57 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)) 14:22:57 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)) 14:22:57 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)) 14:22:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22: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 14:22:59 INFO - Downloading blessings-1.6.tar.gz 14:22:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:22:59 INFO - Installing collected packages: blessings 14:22:59 INFO - Running setup.py install for blessings 14:22:59 INFO - Successfully installed blessings 14:22:59 INFO - Cleaning up... 14:22:59 INFO - Return code: 0 14:22:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:22:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:22:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:00 INFO - Reading from file tmpfile_stdout 14:23:00 INFO - Current package versions: 14:23:00 INFO - blessings == 1.6 14:23:00 INFO - blobuploader == 1.2.4 14:23:00 INFO - docopt == 0.6.1 14:23:00 INFO - manifestparser == 1.1 14:23:00 INFO - mozInstall == 1.12 14:23:00 INFO - mozcrash == 0.17 14:23:00 INFO - mozdebug == 0.1 14:23:00 INFO - mozdevice == 0.48 14:23:00 INFO - mozfile == 1.2 14:23:00 INFO - mozhttpd == 0.7 14:23:00 INFO - mozinfo == 0.9 14:23:00 INFO - mozleak == 0.1 14:23:00 INFO - mozlog == 3.1 14:23:00 INFO - moznetwork == 0.27 14:23:00 INFO - mozprocess == 0.22 14:23:00 INFO - mozprofile == 0.28 14:23:00 INFO - mozrunner == 6.11 14:23:00 INFO - mozscreenshot == 0.1 14:23:00 INFO - mozsystemmonitor == 0.0 14:23:00 INFO - moztest == 0.7 14:23:00 INFO - mozversion == 1.4 14:23:00 INFO - psutil == 3.1.1 14:23:00 INFO - requests == 1.2.3 14:23:00 INFO - wsgiref == 0.1.2 14:23:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:00 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build/tests/talos', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:00 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 14:23:00 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 14:23:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:23:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:00 INFO - 'HOME': '/Users/cltbld', 14:23:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:00 INFO - 'LOGNAME': 'cltbld', 14:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:00 INFO - 'MOZ_NO_REMOTE': '1', 14:23:00 INFO - 'NO_EM_RESTART': '1', 14:23:00 INFO - 'PAGER': '/bin/cat', 14:23:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:00 INFO - 'PWD': '/builds/slave/test', 14:23:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:00 INFO - 'SHELL': '/bin/bash', 14:23:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:23:00 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:23:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:00 INFO - 'USER': 'cltbld', 14:23:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:00 INFO - 'XPC_FLAGS': '0x0', 14:23:00 INFO - 'XPC_SERVICE_NAME': '0', 14:23:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 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)) 14:23:00 INFO - Cleaning up... 14:23:00 INFO - Return code: 0 14:23:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 14:23:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:23:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:23:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:23:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:00 INFO - 'HOME': '/Users/cltbld', 14:23:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:00 INFO - 'LOGNAME': 'cltbld', 14:23:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:00 INFO - 'MOZ_NO_REMOTE': '1', 14:23:00 INFO - 'NO_EM_RESTART': '1', 14:23:00 INFO - 'PAGER': '/bin/cat', 14:23:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:00 INFO - 'PWD': '/builds/slave/test', 14:23:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:00 INFO - 'SHELL': '/bin/bash', 14:23:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:23:00 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:23:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:00 INFO - 'USER': 'cltbld', 14:23:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:00 INFO - 'XPC_FLAGS': '0x0', 14:23:00 INFO - 'XPC_SERVICE_NAME': '0', 14:23:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:00 INFO - Downloading/unpacking jsonschema 14:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:03 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:23:03 INFO - Downloading/unpacking functools32 (from jsonschema) 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 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 14:23:03 INFO - Downloading functools32-3.2.3-2.tar.gz 14:23:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:23:03 INFO - warning: no files found matching '*.txt' 14:23:03 INFO - no previously-included directories found matching 'build' 14:23:03 INFO - no previously-included directories found matching 'dist' 14:23:03 INFO - no previously-included directories found matching '.git*' 14:23:03 INFO - Installing collected packages: jsonschema, functools32 14:23:03 INFO - Running setup.py install for functools32 14:23:03 INFO - warning: no files found matching '*.txt' 14:23:03 INFO - no previously-included directories found matching 'build' 14:23:03 INFO - no previously-included directories found matching 'dist' 14:23:03 INFO - no previously-included directories found matching '.git*' 14:23:03 INFO - Successfully installed jsonschema functools32 14:23:03 INFO - Cleaning up... 14:23:03 INFO - Return code: 0 14:23:03 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 14:23:03 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')]} 14:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:03 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')]} 14:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:03 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 0x1028b8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1028c8420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd28a483070>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}], 'cwd': '/builds/slave/test/build', 'env': {'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 'LOGNAME': 'cltbld', 'USER': 'cltbld', 'XPC_SERVICE_NAME': '0', 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 'HOME': '/Users/cltbld', 'NO_EM_RESTART': '1', 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 'XPCOM_DEBUG_BREAK': 'warn', 'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 'VERSIONER_PYTHON_VERSION': '2.7', 'XPC_FLAGS': '0x0', 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 'MOZ_NO_REMOTE': '1', 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:03 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 14:23:03 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 14:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:03 INFO - 'HOME': '/Users/cltbld', 14:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:03 INFO - 'LOGNAME': 'cltbld', 14:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:03 INFO - 'MOZ_NO_REMOTE': '1', 14:23:03 INFO - 'NO_EM_RESTART': '1', 14:23:03 INFO - 'PAGER': '/bin/cat', 14:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:03 INFO - 'PWD': '/builds/slave/test', 14:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:03 INFO - 'SHELL': '/bin/bash', 14:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:23:03 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:03 INFO - 'USER': 'cltbld', 14:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:03 INFO - 'XPC_FLAGS': '0x0', 14:23:03 INFO - 'XPC_SERVICE_NAME': '0', 14:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:04 INFO - Downloading/unpacking flake8 14:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:06 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 14:23:06 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 14:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:07 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 14:23:07 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 14:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:07 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 14:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 14:23:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:23:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:23:07 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:23:07 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:23:07 INFO - no previously-included directories found matching 'docs/_build' 14:23:08 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 14:23:08 INFO - Running setup.py install for pep8 14:23:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 14:23:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 14:23:08 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 14:23:08 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 14:23:08 INFO - no previously-included directories found matching 'docs/_build' 14:23:08 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 14:23:08 INFO - Successfully installed flake8 mccabe pyflakes pep8 14:23:08 INFO - Cleaning up... 14:23:08 INFO - Return code: 0 14:23:08 INFO - Running post-action listener: _resource_record_post_action 14:23:08 INFO - Running post-action listener: _start_resource_monitoring 14:23:08 INFO - Starting resource monitoring. 14:23:08 INFO - ##### 14:23:08 INFO - ##### Running install step. 14:23:08 INFO - ##### 14:23:08 INFO - Running pre-action listener: _resource_record_pre_action 14:23:08 INFO - Running main action method: install 14:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:08 INFO - Reading from file tmpfile_stdout 14:23:08 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:08 INFO - Reading from file tmpfile_stdout 14:23:08 INFO - Output received: 14:23:08 INFO - Usage: mozinstall [options] installer 14:23:08 INFO - Options: 14:23:08 INFO - -h, --help show this help message and exit 14:23:08 INFO - -d DEST, --destination=DEST 14:23:08 INFO - Directory to install application into. [default: 14:23:08 INFO - "/builds/slave/test"] 14:23:08 INFO - --app=APP Application being installed. [default: firefox] 14:23:08 INFO - mkdir: /builds/slave/test/build/application 14:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-49.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:23:42 INFO - Reading from file tmpfile_stdout 14:23:42 INFO - Output received: 14:23:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:23:42 INFO - Running post-action listener: _resource_record_post_action 14:23:42 INFO - ##### 14:23:42 INFO - ##### Running run-tests step. 14:23:42 INFO - ##### 14:23:42 INFO - Running pre-action listener: _resource_record_pre_action 14:23:42 INFO - Running main action method: run_tests 14:23:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:23:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:23:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:23:42 INFO - 2016-04-27 14:23:42.815 screenresolution[1652:8247] starting screenresolution argv=screenresolution get 14:23:42 INFO - 2016-04-27 14:23:42.818 screenresolution[1652:8247] Display 0: 1600x1200x32@60 14:23:42 INFO - 2016-04-27 14:23:42.824 screenresolution[1653:8249] starting screenresolution argv=screenresolution list 14:23:42 INFO - Available Modes on Display 0 14:23:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:23:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:23:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:23:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:23:43 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:23:43 INFO - Intel Iris: 14:23:43 INFO - Chipset Model: Intel Iris 14:23:43 INFO - Type: GPU 14:23:43 INFO - Bus: Built-In 14:23:43 INFO - VRAM (Dynamic, Max): 1536 MB 14:23:43 INFO - Vendor: Intel (0x8086) 14:23:43 INFO - Device ID: 0x0a2e 14:23:43 INFO - Revision ID: 0x0009 14:23:43 INFO - Displays: 14:23:43 INFO - Display: 14:23:43 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:23:43 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:23:43 INFO - Display Serial Number: 1600x1200 60 14:23:43 INFO - Main Display: Yes 14:23:43 INFO - Mirror: Off 14:23:43 INFO - Online: Yes 14:23:43 INFO - Rotation: Supported 14:23:43 INFO - Return code: 0 14:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:23:43 INFO - Python 2.7.3 14:23:43 INFO - Return code: 0 14:23:43 INFO - grabbing minidump binary from tooltool 14:23:43 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')]} 14:23:43 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102757e00>, '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 0x102580db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102752b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:23:43 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/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:23:43 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/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:23:43 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:23:43 INFO - Return code: 0 14:23:43 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:23:43 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:23:43 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:23:43 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:23:43 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 14:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 14:23:44 INFO - Return code: 0 14:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 14:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0110 --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 14:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.psVdi4XHuV/Render', 14:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:44 INFO - 'HOME': '/Users/cltbld', 14:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:44 INFO - 'LOGNAME': 'cltbld', 14:23:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:23:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:44 INFO - 'MOZ_NO_REMOTE': '1', 14:23:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:23:44 INFO - 'NO_EM_RESTART': '1', 14:23:44 INFO - 'PAGER': '/bin/cat', 14:23:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:44 INFO - 'PWD': '/builds/slave/test', 14:23:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:44 INFO - 'SHELL': '/bin/bash', 14:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners', 14:23:44 INFO - 'TMPDIR': '/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/', 14:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:44 INFO - 'USER': 'cltbld', 14:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:44 INFO - 'XPC_FLAGS': '0x0', 14:23:44 INFO - 'XPC_SERVICE_NAME': '0', 14:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0110', '--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 14:23:44 INFO - mozversion application_buildid: 20160427132147 14:23:44 INFO - mozversion application_changeset: f1ff46a78fa5f30b208b33269b48ba4ba367b34f 14:23:44 INFO - mozversion application_display_name: Nightly 14:23:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:23:44 INFO - mozversion application_name: Firefox 14:23:44 INFO - mozversion application_remotingname: firefox 14:23:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 14:23:44 INFO - mozversion application_vendor: Mozilla 14:23:44 INFO - mozversion application_version: 49.0a1 14:23:44 INFO - mozversion platform_buildid: 20160427132147 14:23:44 INFO - mozversion platform_changeset: f1ff46a78fa5f30b208b33269b48ba4ba367b34f 14:23:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 14:23:44 INFO - mozversion platform_version: 49.0a1 14:23:44 INFO - using testdate: 1461792224 14:23:44 INFO - actual date: 1461792224 14:23:44 INFO - starting webserver on 'localhost:49275' 14:23:44 INFO - SUITE-START | Running 2 tests 14:23:44 INFO - TEST-START | dromaeo_css 14:23:44 INFO - operating with platform_type : mac_ 14:23:44 INFO - Initialising browser for dromaeo_css test... 14:23:44 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpUbKMYI/profile http://localhost:49275/getInfo.html) 14:23:47 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 14:23:47 INFO - PROCESS | 1671 | colorDepth:24 14:23:47 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 14:23:47 INFO - PROCESS | 1671 | __metrics 14:23:47 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:23:47 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:23:47 INFO - TEST-INFO | 1671: exit 0 14:23:47 INFO - Browser initialized. 14:23:47 INFO - Running cycle 1/1 for dromaeo_css test... 14:23:47 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpUbKMYI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:59 INFO - PROCESS | 1674 | RSS: Main: 239235072 14:23:59 INFO - PROCESS | 1674 | 14:24:51 INFO - PROCESS | 1674 | 1461792291037 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] 14:27:00 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 14:27:02 INFO - PROCESS | 1674 | RSS: Main: 367546368 14:27:02 INFO - PROCESS | 1674 | 14:30:05 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 14:30:05 INFO - PROCESS | 1674 | RSS: Main: 374616064 14:30:05 INFO - PROCESS | 1674 | 14:33:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 14:33:08 INFO - PROCESS | 1674 | RSS: Main: 376127488 14:33:08 INFO - PROCESS | 1674 | 14:36:10 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 14:36:11 INFO - PROCESS | 1674 | RSS: Main: 377348096 14:36:11 INFO - PROCESS | 1674 | 14:39:14 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 14:39:14 INFO - PROCESS | 1674 | RSS: Main: 353329152 14:39:14 INFO - PROCESS | 1674 | 14:42:17 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 14:42:18 INFO - PROCESS | 1674 | RSS: Main: 382947328 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | __start_tp_report 14:42:18 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 14:42:18 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 14:42:18 INFO - PROCESS | 1674 | |i|pagename|runs| 14:42:18 INFO - PROCESS | 1674 | |0;dojo.html;3108.9844550777243;3396.354692608404;3397.2526044270253;3407.080088376137;3439.4496880499123;5496.835094947158;5552.361478429977;5565.693886836222;5585.2739143911285;5613.242212301343;5561.360443548993;5583.218349431073;5587.413321601234;5706.315242170935;5724.828255152342;5019.447860735316;5080.491950804918;5103.209002604603;5127.512886275797;5214.452482489341;5210.739463026834;5218.8695282617855;5222.294990176335;5232.790688372461;5266.710330931797;4238.91522169558;5039.420466646346;5052.949470505303;5120.411152717448;5180.119379705459;5082.618803589746;5083.186690129562;5104.948950510485;5112.10538155825;5176.378834539879;5008.5993120550265;5057.5701065409485;5083.6441449098575;5101.744912754348;5107.591392688576;5374.6506477078865;5436.592255580847;5490.835274941758;5550.86122846928;5557.499825015768;5951.851203719898;5962.97018514909;5988.161657367972;5993.61041532299;6002.489788367994;5088.23676448532;6026.397360263983;6032.065029920369;6069.180660610805;6077.392260773931;5587.580931430159;5644.379118296984;5654.46282603152;5742.339630942453;5744.597878148489;6262.937370626236;6267.373262673787;6287.842803929939;6289.308176100625;6292.402221789014;5865;6022.277326720821;6026.969865150646;6033;6038.18484504595;6117.051856962178;6137.171481849982;6156.938430615726;6157.753689852445;6161.353057928943;6103.938960610337;6118.632882027093;6137.101203255298;6145.139680444741;6148.200733904564;6015.639061656315;6085.786997455068;6135.872864236577;6176.567640265138;6186.969065154645;4829.203181475087;4846.975765121152;4866.562009419169;4887.95112048882;4914.926276105862;4347.3044312910415;4351.6083552479795;4358.063016451478;4364.650827933758;4374.846880359172;5435.374931882805;5544.500994910397;5561.138023606348;5562.415946325658;5600.52395546375;6096.829073522031;6102.786402475892;6141.7543298267565;6161.691915404212;6183.81448556544;5552.91670624941;5568.303962004749;5568.6658800472105;5583.0787919992745;5601.103823388238;5205.505476979681;5320.255164277004;5326.280952071421;5341.439148889388;5349.625526213205;6177.907331390033;6233.906491402633;6261.624302541862;6277.874442511215;6281.340459251713;5418.834950485664;5984.341722410444;6156.230471191101;6179.16581261524;6226.533010024175;6176.938230617637;6218.974623724294;6262.373762623702;6266.467350275278;6282.780102696383;5736.971315143564;5997.950358687125;6036.124761909587;6065.484433823174;6090.756369745161;6133.355997620364;6137.447629713347;6154.169187159676;6160.075988601746;6166.105914642262;5802.506786923159;5928.733207005609;5979.4618484336615;5992.43071908168;5993.280806303272;6004.669743163993;6013.398660133952;6033.487153591994;6056.212692349966;6085.178500902499;5835.533157347317;5840.153177789111;5845.590808643524;5852.619579727304;5877.823665290048;5809.8547536311935;5811.27359080115;5912.142739302691;6003.099535069775;6020.096985452217;5800.564957628279;5808.477237048686;5834.38738932423;5835.095560188178;5848.883022339614 14:42:18 INFO - PROCESS | 1674 | |1;ext.html;519.3923109961347;534.2493796216316;560.2296841842467;564.0692856786302;627.6641996531854;29299.41401171978;29773.553396699066;29839.254018649517;29844.253893652643;34135;38357.61642383575;38892.416613750815;38909;38973.805130974375;40730.38904416436;9377.765555861108;9605.231581473501;9622;9624.518774061304;9635.18100961419;8389.161083891593;8613.440126391795;8624.65501379944;9459.959404465504;9602.375845570015;11196.720081997975;11448.08415326776;11462;11467.655970320862;11482.138839587022;11370.260933039368;11488.36813975231;11488.712782180428;11498.94250528742;11499.195056346101;11143;11273.492692828842;11285.210035297532;11301.208915375926;11306.37814920179;36476.0880977975;36956.26087478262;36984.89045328645;38376;38435;1979.9703004454946;2447.5104979004273;2658.853763043032;2971.747401470878;3362.7309815214726;21788;21973.78026219733;26461.3384665383;27401.31496712578;27546.586801198;2666.080202330193;2666.6400035995084;2692.582649689282;2698.136596289188;2817.29567608098;263882;264164.35835641396;266492;267876;267910;44550.55449445529;58694.41305586975;58782.82434351289;58832.705836470544;58909.41090589125;61893.381066189664;62084.379156208764;62101.68949155316;62111;62126.37873621206;16224.432144874872;16815.07517086572;16837.494875153767;16845.241964111643;16849.32602695903;12977.221366718028;13064.934675326562;13066.281354525403;13085.149465284727;13118.147320424332;17270.913645431945;17406.39077632277;17486.82513174852;17559.736603950696;17578.560535986704;5744.569157313218;5945.959457095078;6232;6335.239771227482;6339.936600634027;922.6078916460477;968.3657204530903;991.8561808537721;994.2592768387598;1024.6772266735954;28224.43551129007;28660.713392865804;28916.277093072422;29006.854965725037;29017.274568135967;1046.4610725476387;1102.5369344875012;1112.8163852964333;1116.2744216259496;1129.9604513842141;14618.561443156723;14645.48740794067;14793.63015924589;14813.777793333109;14967.476138335105;365882;456648;465955;465997;467085;56681.43318566844;56998.71500642553;57002.714986424806;57031;57076.85846283134;1327.6017194841509;1356.7422189783908;1357.1178733822896;1488.047649504318;1494.4321157959905;27403;27732.168034958984;27821.132045345228;28100;28274.434511309246;51152.48847511477;52127.47872521226;52206;52326;52406.95186096333;357646;370410;370880.63992001;371010;371174;27528.58707119435;28905;31662.208444789063;37065.62934370622;37411;40359.375233837825;40836.79581602073;40968;41703.58296416997;41892.58107418887;27769.583456248576;34382.48426273658;35153.64846351504;35617.46573801343;36073.81963090168;34817;35133.297334053685;35418.8229058853;35597.28805423929;35728.64271357253;28602.570961436013;35201.82399087988;35629.82185089058;35660.821695892395;35914.64085359113;25924.481510370064;29493.557596636496;30683.693163068085;30737;30748.074259349396;22537.098516059174;22750;22977.88511057434;23222.3033309001;23312.766872331737 14:42:18 INFO - PROCESS | 1674 | |2;jquery.html;359.699650791589;398.03278034376456;401.5282043598772;402.4807997682989;405.7687118342546;16553.66892662146;18745.812541874577;19238.615227695438;19261.903690481544;21295.14819407226;19722.90138549309;20396.082176302065;20567.38297851063;20618.896905515452;20905.85017824019;5798.6230894991795;5872.295324561059;5937.287525496947;6014.548908831834;6036.305824830135;5957.88084238315;5981.0860524632135;6021.337652858182;6022.728977196037;6030.09548567714;5683.630564013326;5980.581359304851;6006.759729610811;6006.8498287542725;6018.9097163542365;5854.97072514639;5946.910796338058;5987.9401205987815;6004.12940123684;6004.369541198177;5206.427292997767;5206.921896171561;5241.42344342122;5245.737713114329;5256.395514515803;12808.359582020861;13232.669183270398;13270.270135142564;13278.601641950758;24785.76071196433;2039.8062184092487;2215.0586000949693;2305.873176975266;2317.953640927172;2463.4457247119435;9437.528123593793;10071.395716256975;10089.445080520569;10124.34191777532;10156.94921525395;1719.6732620802009;1725.5944852959544;1752.6932786762393;1771.7785276840395;1848.7504186934725;95381.52309238478;97632.99999999929;100606;101097;101450.98549014489;9846.507674616241;9924.50377481123;11113.722156946142;11441.256318339281;11519.963203311741;14718.926405367905;14743.631409214855;14760.483383081755;14811.407543698348;14815.555533334018;7515.661795219228;8335.458195217292;8446.788830279293;8657.917760279965;8757.781055473664;7064.293570642997;8519.551676214935;8533.701320453754;8560.229579337765;8662;4413.16149931512;4494.685372023992;4504.324351347259;4568.223402021665;4585.2892801615135;1886.377495426506;1950.9317173898846;1958.8530860185542;1970.4384250488542;1988.6321030609383;872.3326655108847;920.9769755756159;981.7496538382667;983.8475036369374;1027.6403258859489;17975;18178.00020998858;19201.807981920283;19575.80424195768;20071.097619427976;919.2599957034652;945.5366870233536;968.3947532792005;978.574320170728;987.6790043235977;10674.519646615921;10804.24370294072;10808.97314755097;10830.620928267474;10868.67393978182;553799;599989;600222;624880.8755956191;625062;92162;111286;114193.28710069356;116058.83941160649;117731;798.9121196668361;1013.0730381700662;1020.102309967239;1032.2722480651123;1044.5665049004579;14452.709883103149;17753.911230443766;17860.55348616243;17933.641327173646;17951.640967180843;49640.29135652194;49774.75112624414;50047.74976125096;51057.46827595178;51195;63890;66887;70907;71586;73643;7231.847140083538;7571.886421703569;7771;7895.249951254622;8036.758897233092;11242.381669007957;11696.239744416585;11801.881981180079;11925.642230733092;12199.756004879675;8805.779855503664;10124.34191777532;10411.427371494641;10462.319949203278;10560.524776384927;9838.950805245928;9981.201503879818;10344;10353;10498.370097794157;8844.866071428407;10496.947515262375;10704.30422022566;10763.354198747787;10889.455527223765;7493.100827900686;7626.389888808771;7802.843943121219;7879.881801772864;8238.423310368216;8128.105908349959;10178.796424071625;10398.272120951764;10816.783664326827;10857.402842843403 14:42:18 INFO - PROCESS | 1674 | |3;mootools.html;1450.7461194291009;1634.7547867819826;1643.0962970366297;1895.289266525053;2002.6094911492264;7818.609069546521;8618.181272779084;8840.204381605663;8847.247983921374;8849.95575022124;8421.199986001335;8631.136886311366;8633.223009929105;8637.697680581181;8641.265492433151;2427.5630386530415;2465.827392082546;2468.975375219291;2469.394998225437;2470.2465747946867;2072.968905466419;2401.9159329423474;2453.9631905521433;2457.741937096606;2460.9753902460925;2413.4569721812627;2441.9511609767883;2448.155386391692;2450.8529488230765;2456.5578195924727;2151.4621344663833;2357.669926210332;2422.527607116613;2433.3551608823677;2445.730969593343;2387.985106329803;2410.4214988402655;2422.382292515415;2425.1390756281617;2429.8542087474807;8480.66077356905;8620.051794302623;8675.175858293453;8691.782705432352;8707.608157632858;5200.843974680765;5417.674939503642;5424.213489044067;5429.647072940246;5444.020076386249;3294.934101317984;3409.1136304560746;3442.1910850950007;3449.896503104911;3477.2871561329866;943.0478908659078;956.268454632207;962.2638681408728;973.732223638505;989.0554520432969;11565.132615053904;11691.123165762518;11703;11704.53181872724;11713.53145874164;4644.883877903012;4790.640701947368;5026.974865125724;5035.597152227813;5069.670471419419;8951.149640784115;8956.731298061068;9025.187733104052;9025.63897444108;9035.232005279624;5291.629585929025;5326.201069839555;5328.147496400557;5333.066713325153;5335.839924802262;4213.536510983789;4432.623227025739;4963.305137280783;4994.175960966472;5018.623603229699;3717.981410092932;3725.720570957189;3766.7928263945746;3805.9429108563395;3822.847086116524;1183.952641894332;1198.233130796291;1201.5133870782347;1207.4988879615037;1219.920705154162;669.6015870557011;805.2752522729473;857.1214505132165;871.4422769427445;975.7999610079917;5590.105583106765;5681.4034526374135;5783.450572195635;5986;6049.667268300198;875.3303722652176;886.8980067292345;923.1414784250577;930.8091841172543;955.3742298794301;3827.9043023924623;3923.058465968147;3958.9208215835524;3989.4813674222164;3993.520777506718;10146.644867429604;10671.786564268672;10815.513301901434;10925.945370273097;10975.231733778559;10760.838587421194;10900.454977251264;10920.945395272973;10953.671389858317;10976.780464390667;7553.924460755432;7620.428467864932;7812.375009999187;7997.480163789335;8168.836623267502;5708.600397972268;5763.7982670606325;5826.300843898759;5830.5044071254415;6006.849828754316;9749.9025009749;9935.254855885692;9942.552585133542;10029.54867031;10077.949610251902;9061.728148155566;10116.797664046826;10170.796584068425;10285.331453455501;10303.690889273334;7212.4951253411755;7549.0563679540055;7587.772366829004;7594.392448603988;7601.7719468416035;8174.877376839233;8211;8304.169583041648;8314.87527687073;8321.043080045873;7259.6007219601315;7458.73894413693;7499.1750907399055;7555.9244407555225;7578.545287282781;7291.234420385996;7304.452166087458;7365.7790266292095;7500.474966752275;7516;6962.547434416747;7037.507374483942;7044.577325360495;7052.400545953651;7059.717611295696;4683.812647494062;4837.080954618611;4844.612431005582;4956.430010548833;4980.302757614009;4722.409698787652;4765.8570242892765;4768.880777980578;4778.59381952524;4872.951270487391 14:42:18 INFO - PROCESS | 1674 | |4;prototype.html;310.47994609029877;317.9729473799627;326.71902164138845;327.9426100432425;333.71800828300076;4894.902101957959;5201.063808514466;5233.241180028894;5239.816606418776;5260.868478288045;5167.379914410267;5180.766865491052;5181.689098654083;5185.377754669445;5194.922076168851;3866.9365924370813;3892.3577609694507;3996.240714264295;4073.5926407359175;4093.4269202311702;3979.7315530147825;4054.553999060101;4061.776602286873;4073.714839961204;4090.4477895484174;3993.8402463901407;4001.5398229203724;4018.97990510047;4019.9799001004953;4036.414719865618;3818;3823.847046118152;3866.1881004988986;4071.470708807836;4075.53131389891;3112.937741245185;3393.9830300848585;3533.328667553182;3537.4693795930557;3552.982235088808;4139.79301034947;4433.312836510346;4941.802327906879;4956.3804524434445;5081.28861959326;4310.267254566731;4359.280718681413;4602.217623004098;4615.676902616819;4649.605118464481;5185.611079169077;5449.536789372908;5473.67157970523;5480.725963701799;5500.4499550045075;3257.755668324885;3788.6969042476535;3808.733388662795;3811.104390468238;3813.074397429308;5256.7933560528045;5643.971780141073;5804.9419505805245;5809.128630705386;5864.70676466175;3447.3622379859808;3486.4770284457536;3674.684948482439;3708.0532720321507;3866.2460820139804;3911.6139155115357;3918.9020274493364;3924.2543916655745;3925.9803700981884;3931.5871833457645;4233.343831706091;4397.142557201315;4545.772711364419;4547.3406356078185;4555.772211389417;4846.376463884568;5553.972230138824;5555.8608836417125;5593.46862048113;5607.663540187602;1230.5139469909514;1234.9256147152034;1425.4084554909803;1433.175923843794;1433.1902475101533;891.8394688955921;894.2443635128307;905.4431524612316;936.5364144748393;944.2163004706077;4370.672199585044;4860.8784780380065;5092.479222784746;5151.201563757623;5184.766685499161;4764.6182607043565;4949.876253093701;4991.301217829507;5559.583031272671;5625.943740562624;4254.340577210537;4476.462824461086;4994.650374473825;5059.266406371055;5126.256692779526;3216.517522371663;3228.047725920859;3237.4460259075304;3244.270039241152;3271.378438096754;6228.968855155696;6412.8777463943625;6413.390727880844;6469.191351081115;6495.512836537278;5745.942540574624;5758.654480731169;6200.782972595937;6247.937520624827;6305.747770089145;5463.781448742086;6336.398042186077;6372.458341041063;6398.616083035033;6405.519586030973;5097.719625420637;6036.9698151508965;6094.268687757498;6120.265568131675;6144.170536977451;5114.9232761507865;5573.303337082864;6030;6040.909386359296;6062.90905636407;6148.907766383597;6402.679866006588;6404.967975160094;6417.807465776034;6425.0082474433075;4059.3559608358664;4059.6143366380147;4075.6535694465115;4083.612056854594;4090.813664037396;6084.39156084388;6126.816195514141;6128.32588415265;6132.5707200495535;6137.294211165773;5443.4284400138995;5625.746841392229;5929.822105336847;5934.43622855828;6011.609245399035;5162;5340.439253878287;5503.2020357048805;5897.351291358033;5932.762689492372;4355.520892701864;5709.34342550612;5714.828270310018;5722.74247658848;5767.9134812977;4949.82675606352;5059.8495639352395;5493.807716729895;5496.7526461310135;5545.3622833374675;4590.77046147698;5367.114426119647;5385.299911011543;5405.378381486022;5460.453954604508 14:42:18 INFO - PROCESS | 1674 | |5;yui.html;690.1476676304766;693.9340762627548;712.1063065852356;743.546436673629;757.939364850812;14717.411303547871;14824.110553366792;14839.40642374305;15126.621834454158;15376.538703838904;14097.647558811035;14161.78757318641;14189.503367382144;14239.71520569588;14282.3572939218;706.8232941764559;730.2660825869993;891.3136884598855;896.4038914122101;898.0301274623424;728.5191773429538;732.904722386089;733.7761982595306;876.31647315094;922.8154369126169;960.265396971314;965.3532133470592;965.9130678238957;969.0938972061145;974.1768205866067;958.2142643032716;958.6261358070358;959.472290240365;960.2558017536395;961.0101595356758;553.1371061144628;690.8169335126196;708.0865683268578;711.7295427737427;714.9320814522612;15063.54809355721;15101.018433801768;15109.47116850905;15116.470923517623;15131.621709457242;12120.878791212062;12309.446074926649;12312.507499700001;12641.747165056648;12747.808782868264;9844.3601165924;9994.400335979864;10188.643397481053;10285.382877027327;10471;3382.678645528671;3461.8961431157104;3471.826408679581;3529.699975502085;3736.3834967230373;78202.60898695527;79302;79816;80059;80479;9213.95393023044;9484.95257523708;9508.477033763209;9720.27097967641;9801.411915285105;21807.890960545414;21862.781372186393;21866.517363940355;21907.561848762936;22018.889905550368;7403.5928023959195;7454.068241469817;7468.103827540621;7513.624318784039;7627.466077374642;4019.41718450823;4082.081531655354;4115.52671442782;4134.131832315187;4343.652507799368;3481.1819222482695;3560.6973407260675;3569;3582.3730847102174;3709.1283548366114;636.8089573128043;648.3225029843825;655.8393193667582;661.1207094564314;669.7241754457668;490.94845041270867;555.6888142640134;569.481771587853;605.9182010428535;616.1804799616589;8193;8269.669213231524;8315;8399;8506.31949444043;508.1970486631114;610.2646311194999;618.8050764009323;624.4348864277836;624.6015941176754;3894.337962546374;3927;3955.881323560298;3997.420374045747;4040.252553277653;97222;106148.46925765321;106369;106926;108757.45621271843;79707.80438293431;80920.59539702264;81140.18859811444;83333.58333208416;83699;67347;67809.32190678129;68015.31984680188;68461.65769171221;68706.65646671735;6120.724567394389;7811.843763124819;7864.134945156143;7969.681212751425;7972.561509117054;46100;46126.769366152956;46752.29871551997;47391.289130663754;47926.28110578414;54927.72536137294;57680.26959191231;67426.66286668535;67487.66256168688;70030;10912.508937097691;11145.331280123219;11341.716457088638;11362.375069371265;11546.76906461883;11798;11839.408029598659;11910.464029118537;12172.2696638202;12490.87509124897;11452.083833293484;11530.827037594276;11784.646460606196;11832.881671183179;12155.81766273489;11457.885421146015;11564.306141631529;11597.594084207012;11841.46713397882;11856.466459009538;11093.667189984313;11172.441377931233;11293.491792869176;11330.716732081764;11679.299242045505;9669.226461883172;9755.268354873271;9908;9908.405495670282;10037.498125093862;8429.915700842914;9381.671641492516;9431;9849.852252216366;9875 14:42:18 INFO - PROCESS | 1674 | __end_tp_report 14:42:18 INFO - PROCESS | 1674 | __start_cc_report 14:42:18 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1331 14:42:18 INFO - PROCESS | 1674 | __end_cc_report 14:42:18 INFO - PROCESS | 1674 | __startTimestamp1461793338228__endTimestamp 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | ------- Summary: start ------- 14:42:18 INFO - PROCESS | 1674 | Number of tests: 6 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | [#0] dojo.html Cycles:165 Average:5625.31 Median:5811.27 stddev:628.25 (10.8%) stddev-sans-first:598.36 14:42:18 INFO - PROCESS | 1674 | Values: 3109.0 3396.4 3397.3 3407.1 3439.4 5496.8 5552.4 5565.7 5585.3 5613.2 5561.4 5583.2 5587.4 5706.3 5724.8 5019.4 5080.5 5103.2 5127.5 5214.5 5210.7 5218.9 5222.3 5232.8 5266.7 4238.9 5039.4 5052.9 5120.4 5180.1 5082.6 5083.2 5104.9 5112.1 5176.4 5008.6 5057.6 5083.6 5101.7 5107.6 5374.7 5436.6 5490.8 5550.9 5557.5 5951.9 5963.0 5988.2 5993.6 6002.5 5088.2 6026.4 6032.1 6069.2 6077.4 5587.6 5644.4 5654.5 5742.3 5744.6 6262.9 6267.4 6287.8 6289.3 6292.4 5865.0 6022.3 6027.0 6033.0 6038.2 6117.1 6137.2 6156.9 6157.8 6161.4 6103.9 6118.6 6137.1 6145.1 6148.2 6015.6 6085.8 6135.9 6176.6 6187.0 4829.2 4847.0 4866.6 4888.0 4914.9 4347.3 4351.6 4358.1 4364.7 4374.8 5435.4 5544.5 5561.1 5562.4 5600.5 6096.8 6102.8 6141.8 6161.7 6183.8 5552.9 5568.3 5568.7 5583.1 5601.1 5205.5 5320.3 5326.3 5341.4 5349.6 6177.9 6233.9 6261.6 6277.9 6281.3 5418.8 5984.3 6156.2 6179.2 6226.5 6176.9 6219.0 6262.4 6266.5 6282.8 5737.0 5998.0 6036.1 6065.5 6090.8 6133.4 6137.4 6154.2 6160.1 6166.1 5802.5 5928.7 5979.5 5992.4 5993.3 6004.7 6013.4 6033.5 6056.2 6085.2 5835.5 5840.2 5845.6 5852.6 5877.8 5809.9 5811.3 5912.1 6003.1 6020.1 5800.6 5808.5 5834.4 5835.1 5848.9 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | [#1] ext.html Cycles:180 Average:51505.16 Median:30710.35 stddev:97209.29 (316.5%) stddev-sans-first:97406.61 14:42:18 INFO - PROCESS | 1674 | Values: 519.4 534.2 560.2 564.1 627.7 29299.4 29773.6 29839.3 29844.3 34135.0 38357.6 38892.4 38909.0 38973.8 40730.4 9377.8 9605.2 9622.0 9624.5 9635.2 8389.2 8613.4 8624.7 9460.0 9602.4 11196.7 11448.1 11462.0 11467.7 11482.1 11370.3 11488.4 11488.7 11498.9 11499.2 11143.0 11273.5 11285.2 11301.2 11306.4 36476.1 36956.3 36984.9 38376.0 38435.0 1980.0 2447.5 2658.9 2971.7 3362.7 21788.0 21973.8 26461.3 27401.3 27546.6 2666.1 2666.6 2692.6 2698.1 2817.3 263882.0 264164.4 266492.0 267876.0 267910.0 44550.6 58694.4 58782.8 58832.7 58909.4 61893.4 62084.4 62101.7 62111.0 62126.4 16224.4 16815.1 16837.5 16845.2 16849.3 12977.2 13064.9 13066.3 13085.1 13118.1 17270.9 17406.4 17486.8 17559.7 17578.6 5744.6 5946.0 6232.0 6335.2 6339.9 922.6 968.4 991.9 994.3 1024.7 28224.4 28660.7 28916.3 29006.9 29017.3 1046.5 1102.5 1112.8 1116.3 1130.0 14618.6 14645.5 14793.6 14813.8 14967.5 365882.0 456648.0 465955.0 465997.0 467085.0 56681.4 56998.7 57002.7 57031.0 57076.9 1327.6 1356.7 1357.1 1488.0 1494.4 27403.0 27732.2 27821.1 28100.0 28274.4 51152.5 52127.5 52206.0 52326.0 52407.0 357646.0 370410.0 370880.6 371010.0 371174.0 27528.6 28905.0 31662.2 37065.6 37411.0 40359.4 40836.8 40968.0 41703.6 41892.6 27769.6 34382.5 35153.6 35617.5 36073.8 34817.0 35133.3 35418.8 35597.3 35728.6 28602.6 35201.8 35629.8 35660.8 35914.6 25924.5 29493.6 30683.7 30737.0 30748.1 22537.1 22750.0 22977.9 23222.3 23312.8 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | [#2] jquery.html Cycles:180 Average:33192.07 Median:399.78 stddev:99537.87 (24898.1%) stddev-sans-first:99786.57 14:42:18 INFO - PROCESS | 1674 | Values: 359.7 398.0 401.5 402.5 405.8 16553.7 18745.8 19238.6 19261.9 21295.1 19722.9 20396.1 20567.4 20618.9 20905.9 5798.6 5872.3 5937.3 6014.5 6036.3 5957.9 5981.1 6021.3 6022.7 6030.1 5683.6 5980.6 6006.8 6006.8 6018.9 5855.0 5946.9 5987.9 6004.1 6004.4 5206.4 5206.9 5241.4 5245.7 5256.4 12808.4 13232.7 13270.3 13278.6 24785.8 2039.8 2215.1 2305.9 2318.0 2463.4 9437.5 10071.4 10089.4 10124.3 10156.9 1719.7 1725.6 1752.7 1771.8 1848.8 95381.5 97633.0 100606.0 101097.0 101451.0 9846.5 9924.5 11113.7 11441.3 11520.0 14718.9 14743.6 14760.5 14811.4 14815.6 7515.7 8335.5 8446.8 8657.9 8757.8 7064.3 8519.6 8533.7 8560.2 8662.0 4413.2 4494.7 4504.3 4568.2 4585.3 1886.4 1950.9 1958.9 1970.4 1988.6 872.3 921.0 981.7 983.8 1027.6 17975.0 18178.0 19201.8 19575.8 20071.1 919.3 945.5 968.4 978.6 987.7 10674.5 10804.2 10809.0 10830.6 10868.7 553799.0 599989.0 600222.0 624880.9 625062.0 92162.0 111286.0 114193.3 116058.8 117731.0 798.9 1013.1 1020.1 1032.3 1044.6 14452.7 17753.9 17860.6 17933.6 17951.6 49640.3 49774.8 50047.7 51057.5 51195.0 63890.0 66887.0 70907.0 71586.0 73643.0 7231.8 7571.9 7771.0 7895.2 8036.8 11242.4 11696.2 11801.9 11925.6 12199.8 8805.8 10124.3 10411.4 10462.3 10560.5 9839.0 9981.2 10344.0 10353.0 10498.4 8844.9 10496.9 10704.3 10763.4 10889.5 7493.1 7626.4 7802.8 7879.9 8238.4 8128.1 10178.8 10398.3 10816.8 10857.4 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | [#3] mootools.html Cycles:180 Average:5666.35 Median:5135.26 stddev:3162.90 (61.6%) stddev-sans-first:3155.91 14:42:18 INFO - PROCESS | 1674 | Values: 1450.7 1634.8 1643.1 1895.3 2002.6 7818.6 8618.2 8840.2 8847.2 8850.0 8421.2 8631.1 8633.2 8637.7 8641.3 2427.6 2465.8 2469.0 2469.4 2470.2 2073.0 2401.9 2454.0 2457.7 2461.0 2413.5 2442.0 2448.2 2450.9 2456.6 2151.5 2357.7 2422.5 2433.4 2445.7 2388.0 2410.4 2422.4 2425.1 2429.9 8480.7 8620.1 8675.2 8691.8 8707.6 5200.8 5417.7 5424.2 5429.6 5444.0 3294.9 3409.1 3442.2 3449.9 3477.3 943.0 956.3 962.3 973.7 989.1 11565.1 11691.1 11703.0 11704.5 11713.5 4644.9 4790.6 5027.0 5035.6 5069.7 8951.1 8956.7 9025.2 9025.6 9035.2 5291.6 5326.2 5328.1 5333.1 5335.8 4213.5 4432.6 4963.3 4994.2 5018.6 3718.0 3725.7 3766.8 3805.9 3822.8 1184.0 1198.2 1201.5 1207.5 1219.9 669.6 805.3 857.1 871.4 975.8 5590.1 5681.4 5783.5 5986.0 6049.7 875.3 886.9 923.1 930.8 955.4 3827.9 3923.1 3958.9 3989.5 3993.5 10146.6 10671.8 10815.5 10925.9 10975.2 10760.8 10900.5 10920.9 10953.7 10976.8 7553.9 7620.4 7812.4 7997.5 8168.8 5708.6 5763.8 5826.3 5830.5 6006.8 9749.9 9935.3 9942.6 10029.5 10077.9 9061.7 10116.8 10170.8 10285.3 10303.7 7212.5 7549.1 7587.8 7594.4 7601.8 8174.9 8211.0 8304.2 8314.9 8321.0 7259.6 7458.7 7499.2 7555.9 7578.5 7291.2 7304.5 7365.8 7500.5 7516.0 6962.5 7037.5 7044.6 7052.4 7059.7 4683.8 4837.1 4844.6 4956.4 4980.3 4722.4 4765.9 4768.9 4778.6 4873.0 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | [#4] prototype.html Cycles:180 Average:4615.62 Median:5095.10 stddev:1453.01 (28.5%) stddev-sans-first:1420.70 14:42:18 INFO - PROCESS | 1674 | Values: 310.5 318.0 326.7 327.9 333.7 4894.9 5201.1 5233.2 5239.8 5260.9 5167.4 5180.8 5181.7 5185.4 5194.9 3866.9 3892.4 3996.2 4073.6 4093.4 3979.7 4054.6 4061.8 4073.7 4090.4 3993.8 4001.5 4019.0 4020.0 4036.4 3818.0 3823.8 3866.2 4071.5 4075.5 3112.9 3394.0 3533.3 3537.5 3553.0 4139.8 4433.3 4941.8 4956.4 5081.3 4310.3 4359.3 4602.2 4615.7 4649.6 5185.6 5449.5 5473.7 5480.7 5500.4 3257.8 3788.7 3808.7 3811.1 3813.1 5256.8 5644.0 5804.9 5809.1 5864.7 3447.4 3486.5 3674.7 3708.1 3866.2 3911.6 3918.9 3924.3 3926.0 3931.6 4233.3 4397.1 4545.8 4547.3 4555.8 4846.4 5554.0 5555.9 5593.5 5607.7 1230.5 1234.9 1425.4 1433.2 1433.2 891.8 894.2 905.4 936.5 944.2 4370.7 4860.9 5092.5 5151.2 5184.8 4764.6 4949.9 4991.3 5559.6 5625.9 4254.3 4476.5 4994.7 5059.3 5126.3 3216.5 3228.0 3237.4 3244.3 3271.4 6229.0 6412.9 6413.4 6469.2 6495.5 5745.9 5758.7 6200.8 6247.9 6305.7 5463.8 6336.4 6372.5 6398.6 6405.5 5097.7 6037.0 6094.3 6120.3 6144.2 5114.9 5573.3 6030.0 6040.9 6062.9 6148.9 6402.7 6405.0 6417.8 6425.0 4059.4 4059.6 4075.7 4083.6 4090.8 6084.4 6126.8 6128.3 6132.6 6137.3 5443.4 5625.7 5929.8 5934.4 6011.6 5162.0 5340.4 5503.2 5897.4 5932.8 4355.5 5709.3 5714.8 5722.7 5767.9 4949.8 5059.8 5493.8 5496.8 5545.4 4590.8 5367.1 5385.3 5405.4 5460.5 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - PROCESS | 1674 | [#5] yui.html Cycles:180 Average:18493.74 Median:608.09 stddev:26559.64 (4367.7%) stddev-sans-first:26600.51 14:42:18 INFO - PROCESS | 1674 | Values: 690.1 693.9 712.1 743.5 757.9 14717.4 14824.1 14839.4 15126.6 15376.5 14097.6 14161.8 14189.5 14239.7 14282.4 706.8 730.3 891.3 896.4 898.0 728.5 732.9 733.8 876.3 922.8 960.3 965.4 965.9 969.1 974.2 958.2 958.6 959.5 960.3 961.0 553.1 690.8 708.1 711.7 714.9 15063.5 15101.0 15109.5 15116.5 15131.6 12120.9 12309.4 12312.5 12641.7 12747.8 9844.4 9994.4 10188.6 10285.4 10471.0 3382.7 3461.9 3471.8 3529.7 3736.4 78202.6 79302.0 79816.0 80059.0 80479.0 9214.0 9485.0 9508.5 9720.3 9801.4 21807.9 21862.8 21866.5 21907.6 22018.9 7403.6 7454.1 7468.1 7513.6 7627.5 4019.4 4082.1 4115.5 4134.1 4343.7 3481.2 3560.7 3569.0 3582.4 3709.1 636.8 648.3 655.8 661.1 669.7 490.9 555.7 569.5 605.9 616.2 8193.0 8269.7 8315.0 8399.0 8506.3 508.2 610.3 618.8 624.4 624.6 3894.3 3927.0 3955.9 3997.4 4040.3 97222.0 106148.5 106369.0 106926.0 108757.5 79707.8 80920.6 81140.2 83333.6 83699.0 67347.0 67809.3 68015.3 68461.7 68706.7 6120.7 7811.8 7864.1 7969.7 7972.6 46100.0 46126.8 46752.3 47391.3 47926.3 54927.7 57680.3 67426.7 67487.7 70030.0 10912.5 11145.3 11341.7 11362.4 11546.8 11798.0 11839.4 11910.5 12172.3 12490.9 11452.1 11530.8 11784.6 11832.9 12155.8 11457.9 11564.3 11597.6 11841.5 11856.5 11093.7 11172.4 11293.5 11330.7 11679.3 9669.2 9755.3 9908.0 9908.4 10037.5 8429.9 9381.7 9431.0 9849.9 9875.0 14:42:18 INFO - PROCESS | 1674 | -------- Summary: end -------- 14:42:18 INFO - PROCESS | 1674 | 14:42:18 INFO - TEST-INFO | 1674: exit 0 14:42:18 INFO - TEST-OK | dromaeo_css | took 1114303ms 14:42:18 INFO - TEST-START | kraken 14:42:18 INFO - operating with platform_type : mac_ 14:42:18 INFO - Initialising browser for kraken test... 14:42:18 INFO - TEST-INFO | started process 1680 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpP57EWp/profile http://localhost:49275/getInfo.html) 14:42:20 INFO - PROCESS | 1680 | __metrics Screen width/height:1600/1200 14:42:20 INFO - PROCESS | 1680 | colorDepth:24 14:42:20 INFO - PROCESS | 1680 | Browser inner width/height: 1024/689 14:42:20 INFO - PROCESS | 1680 | __metrics 14:42:20 INFO - PROCESS | 1680 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:42:20 ERROR - PROCESS | 1680 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 14:42:21 INFO - TEST-INFO | 1680: exit 0 14:42:21 INFO - Browser initialized. 14:42:21 INFO - Running cycle 1/1 for kraken test... 14:42:21 INFO - TEST-INFO | started process 1682 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/tmpP57EWp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:42:33 INFO - PROCESS | 1682 | RSS: Main: 239575040 14:42:33 INFO - PROCESS | 1682 | 14:42:36 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 14:42:37 INFO - PROCESS | 1682 | RSS: Main: 287223808 14:42:37 INFO - PROCESS | 1682 | 14:42:39 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 14:42:40 INFO - PROCESS | 1682 | RSS: Main: 300367872 14:42:40 INFO - PROCESS | 1682 | 14:42:43 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 14:42:44 INFO - PROCESS | 1682 | RSS: Main: 387403776 14:42:44 INFO - PROCESS | 1682 | 14:42:46 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 14:42:47 INFO - PROCESS | 1682 | RSS: Main: 288436224 14:42:47 INFO - PROCESS | 1682 | 14:42:49 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:42:49 INFO - PROCESS | 1682 | RSS: Main: 292544512 14:42:49 INFO - PROCESS | 1682 | 14:42:52 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:53 INFO - PROCESS | 1682 | RSS: Main: 331247616 14:42:53 INFO - PROCESS | 1682 | 14:42:56 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:56 INFO - PROCESS | 1682 | RSS: Main: 330940416 14:42:56 INFO - PROCESS | 1682 | 14:42:59 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 14:43:00 INFO - PROCESS | 1682 | RSS: Main: 329916416 14:43:00 INFO - PROCESS | 1682 | 14:43:01 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:43:02 INFO - PROCESS | 1682 | RSS: Main: 321900544 14:43:02 INFO - PROCESS | 1682 | 14:43:04 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:43:04 INFO - PROCESS | 1682 | RSS: Main: 329388032 14:43:04 INFO - PROCESS | 1682 | 14:43:06 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:43:07 INFO - PROCESS | 1682 | RSS: Main: 331141120 14:43:07 INFO - PROCESS | 1682 | 14:43:10 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:43:11 INFO - PROCESS | 1682 | RSS: Main: 335003648 14:43:11 INFO - PROCESS | 1682 | 14:43:14 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:43:14 INFO - PROCESS | 1682 | RSS: Main: 332955648 14:43:14 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 14:43:16 INFO - PROCESS | 1682 | RSS: Main: 397549568 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | __start_tp_report 14:43:16 INFO - PROCESS | 1682 | _x_x_mozilla_page_load 14:43:16 INFO - PROCESS | 1682 | _x_x_mozilla_page_load_details 14:43:16 INFO - PROCESS | 1682 | |i|pagename|runs| 14:43:16 INFO - PROCESS | 1682 | |0;ai-astar;113;112;108;112;113;113;113;113;104;110 14:43:16 INFO - PROCESS | 1682 | |1;audio-beat-detection;155;123;145;171;122;142;141;121;173;116 14:43:16 INFO - PROCESS | 1682 | |2;audio-dft;183;184;186;185;184;180;182;185;185;186 14:43:16 INFO - PROCESS | 1682 | |3;audio-fft;77;71;89;89;56;58;58;81;102;79 14:43:16 INFO - PROCESS | 1682 | |4;audio-oscillator;92;97;135;78;79;79;124;80;77;125 14:43:16 INFO - PROCESS | 1682 | |5;imaging-gaussian-blur;90;87;90;91;88;88;93;88;89;86 14:43:16 INFO - PROCESS | 1682 | |6;imaging-darkroom;97;94;92;93;93;93;93;94;93;93 14:43:16 INFO - PROCESS | 1682 | |7;imaging-desaturate;85;84;84;83;86;85;84;84;84;87 14:43:16 INFO - PROCESS | 1682 | |8;json-parse-financial;58;60;59;58;60;60;58;77;60;58 14:43:16 INFO - PROCESS | 1682 | |9;json-stringify-tinderbox;64;64;75;73;70;70;66;68;68;68 14:43:16 INFO - PROCESS | 1682 | |10;stanford-crypto-aes;82;78;81;83;83;82;81;83;81;82 14:43:16 INFO - PROCESS | 1682 | |11;stanford-crypto-ccm;138;140;142;136;140;136;137;142;195;157 14:43:16 INFO - PROCESS | 1682 | |12;stanford-crypto-pbkdf2;195;198;196;202;199;209;265;219;205;191 14:43:16 INFO - PROCESS | 1682 | |13;stanford-crypto-sha256-iterative;72;70;70;71;70;69;70;71;70;70 14:43:16 INFO - PROCESS | 1682 | __end_tp_report 14:43:16 INFO - PROCESS | 1682 | __start_cc_report 14:43:16 INFO - PROCESS | 1682 | _x_x_mozilla_cycle_collect,2045 14:43:16 INFO - PROCESS | 1682 | __end_cc_report 14:43:16 INFO - PROCESS | 1682 | __startTimestamp1461793396902__endTimestamp 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | ------- Summary: start ------- 14:43:16 INFO - PROCESS | 1682 | Number of tests: 14 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#0] ai-astar Cycles:10 Average:111.10 Median:113.00 stddev:3.00 (2.7%) stddev-sans-first:3.10 14:43:16 INFO - PROCESS | 1682 | Values: 113.0 112.0 108.0 112.0 113.0 113.0 113.0 113.0 104.0 110.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#1] audio-beat-detection Cycles:10 Average:140.90 Median:143.50 stddev:20.67 (14.4%) stddev-sans-first:21.29 14:43:16 INFO - PROCESS | 1682 | Values: 155.0 123.0 145.0 171.0 122.0 142.0 141.0 121.0 173.0 116.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#2] audio-dft Cycles:10 Average:184.00 Median:185.00 stddev:1.89 (1.0%) stddev-sans-first:1.96 14:43:16 INFO - PROCESS | 1682 | Values: 183.0 184.0 186.0 185.0 184.0 180.0 182.0 185.0 185.0 186.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#3] audio-fft Cycles:10 Average:76.00 Median:78.00 stddev:15.36 (19.7%) stddev-sans-first:16.28 14:43:16 INFO - PROCESS | 1682 | Values: 77.0 71.0 89.0 89.0 56.0 58.0 58.0 81.0 102.0 79.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#4] audio-oscillator Cycles:10 Average:96.60 Median:79.00 stddev:22.80 (28.9%) stddev-sans-first:24.12 14:43:16 INFO - PROCESS | 1682 | Values: 92.0 97.0 135.0 78.0 79.0 79.0 124.0 80.0 77.0 125.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#5] imaging-gaussian-blur Cycles:10 Average:89.00 Median:89.50 stddev:2.05 (2.3%) stddev-sans-first:2.15 14:43:16 INFO - PROCESS | 1682 | Values: 90.0 87.0 90.0 91.0 88.0 88.0 93.0 88.0 89.0 86.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#6] imaging-darkroom Cycles:10 Average:93.50 Median:93.00 stddev:1.35 (1.5%) stddev-sans-first:0.60 14:43:16 INFO - PROCESS | 1682 | Values: 97.0 94.0 92.0 93.0 93.0 93.0 93.0 94.0 93.0 93.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.17 (1.4%) stddev-sans-first:1.24 14:43:16 INFO - PROCESS | 1682 | Values: 85.0 84.0 84.0 83.0 86.0 85.0 84.0 84.0 84.0 87.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:60.00 stddev:5.77 (9.6%) stddev-sans-first:6.03 14:43:16 INFO - PROCESS | 1682 | Values: 58.0 60.0 59.0 58.0 60.0 60.0 58.0 77.0 60.0 58.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#9] json-stringify-tinderbox Cycles:10 Average:68.60 Median:69.00 stddev:3.57 (5.2%) stddev-sans-first:3.37 14:43:16 INFO - PROCESS | 1682 | Values: 64.0 64.0 75.0 73.0 70.0 70.0 66.0 68.0 68.0 68.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#10] stanford-crypto-aes Cycles:10 Average:81.60 Median:82.00 stddev:1.51 (1.8%) stddev-sans-first:1.59 14:43:16 INFO - PROCESS | 1682 | Values: 82.0 78.0 81.0 83.0 83.0 82.0 81.0 83.0 81.0 82.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#11] stanford-crypto-ccm Cycles:10 Average:146.30 Median:141.00 stddev:18.17 (12.9%) stddev-sans-first:19.02 14:43:16 INFO - PROCESS | 1682 | Values: 138.0 140.0 142.0 136.0 140.0 136.0 137.0 142.0 195.0 157.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:207.90 Median:203.50 stddev:21.60 (10.6%) stddev-sans-first:22.40 14:43:16 INFO - PROCESS | 1682 | Values: 195.0 198.0 196.0 202.0 199.0 209.0 265.0 219.0 205.0 191.0 14:43:16 INFO - PROCESS | 1682 | 14:43:16 INFO - PROCESS | 1682 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.30 Median:70.00 stddev:0.82 (1.2%) stddev-sans-first:0.60 14:43:16 INFO - PROCESS | 1682 | Values: 72.0 70.0 70.0 71.0 70.0 69.0 70.0 71.0 70.0 70.0 14:43:16 INFO - PROCESS | 1682 | -------- Summary: end -------- 14:43:16 INFO - PROCESS | 1682 | 14:43:17 INFO - TEST-INFO | 1682: exit 0 14:43:17 INFO - TEST-OK | kraken | took 58898ms 14:43:17 INFO - SUITE-END | took 1173s 14:43:18 INFO - Completed test suite (00:19:33) 14:43:18 INFO - javascript benchmark 14:43:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3108.9844550777243, 3396.354692608404, 3397.2526044270253, 3407.080088376137, 3439.4496880499123, 5496.835094947158, 5552.361478429977, 5565.693886836222, 5585.2739143911285, 5613.242212301343, 5561.360443548993, 5583.218349431073, 5587.413321601234, 5706.315242170935, 5724.828255152342, 5019.447860735316, 5080.491950804918, 5103.209002604603, 5127.512886275797, 5214.452482489341, 5210.739463026834, 5218.8695282617855, 5222.294990176335, 5232.790688372461, 5266.710330931797, 4238.91522169558, 5039.420466646346, 5052.949470505303, 5120.411152717448, 5180.119379705459, 5082.618803589746, 5083.186690129562, 5104.948950510485, 5112.10538155825, 5176.378834539879, 5008.5993120550265, 5057.5701065409485, 5083.6441449098575, 5101.744912754348, 5107.591392688576, 5374.6506477078865, 5436.592255580847, 5490.835274941758, 5550.86122846928, 5557.499825015768, 5951.851203719898, 5962.97018514909, 5988.161657367972, 5993.61041532299, 6002.489788367994, 5088.23676448532, 6026.397360263983, 6032.065029920369, 6069.180660610805, 6077.392260773931, 5587.580931430159, 5644.379118296984, 5654.46282603152, 5742.339630942453, 5744.597878148489, 6262.937370626236, 6267.373262673787, 6287.842803929939, 6289.308176100625, 6292.402221789014, 5865.0, 6022.277326720821, 6026.969865150646, 6033.0, 6038.18484504595, 6117.051856962178, 6137.171481849982, 6156.938430615726, 6157.753689852445, 6161.353057928943, 6103.938960610337, 6118.632882027093, 6137.101203255298, 6145.139680444741, 6148.200733904564, 6015.639061656315, 6085.786997455068, 6135.872864236577, 6176.567640265138, 6186.969065154645, 4829.203181475087, 4846.975765121152, 4866.562009419169, 4887.95112048882, 4914.926276105862, 4347.3044312910415, 4351.6083552479795, 4358.063016451478, 4364.650827933758, 4374.846880359172, 5435.374931882805, 5544.500994910397, 5561.138023606348, 5562.415946325658, 5600.52395546375, 6096.829073522031, 6102.786402475892, 6141.7543298267565, 6161.691915404212, 6183.81448556544, 5552.91670624941, 5568.303962004749, 5568.6658800472105, 5583.0787919992745, 5601.103823388238, 5205.505476979681, 5320.255164277004, 5326.280952071421, 5341.439148889388, 5349.625526213205, 6177.907331390033, 6233.906491402633, 6261.624302541862, 6277.874442511215, 6281.340459251713, 5418.834950485664, 5984.341722410444, 6156.230471191101, 6179.16581261524, 6226.533010024175, 6176.938230617637, 6218.974623724294, 6262.373762623702, 6266.467350275278, 6282.780102696383, 5736.971315143564, 5997.950358687125, 6036.124761909587, 6065.484433823174, 6090.756369745161, 6133.355997620364, 6137.447629713347, 6154.169187159676, 6160.075988601746, 6166.105914642262, 5802.506786923159, 5928.733207005609, 5979.4618484336615, 5992.43071908168, 5993.280806303272, 6004.669743163993, 6013.398660133952, 6033.487153591994, 6056.212692349966, 6085.178500902499, 5835.533157347317, 5840.153177789111, 5845.590808643524, 5852.619579727304, 5877.823665290048, 5809.8547536311935, 5811.27359080115, 5912.142739302691, 6003.099535069775, 6020.096985452217, 5800.564957628279, 5808.477237048686, 5834.38738932423, 5835.095560188178, 5848.883022339614], "value": 5585.624092675837, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.3923109961347, 534.2493796216316, 560.2296841842467, 564.0692856786302, 627.6641996531854, 29299.41401171978, 29773.553396699066, 29839.254018649517, 29844.253893652643, 34135.0, 38357.61642383575, 38892.416613750815, 38909.0, 38973.805130974375, 40730.38904416436, 9377.765555861108, 9605.231581473501, 9622.0, 9624.518774061304, 9635.18100961419, 8389.161083891593, 8613.440126391795, 8624.65501379944, 9459.959404465504, 9602.375845570015, 11196.720081997975, 11448.08415326776, 11462.0, 11467.655970320862, 11482.138839587022, 11370.260933039368, 11488.36813975231, 11488.712782180428, 11498.94250528742, 11499.195056346101, 11143.0, 11273.492692828842, 11285.210035297532, 11301.208915375926, 11306.37814920179, 36476.0880977975, 36956.26087478262, 36984.89045328645, 38376.0, 38435.0, 1979.9703004454946, 2447.5104979004273, 2658.853763043032, 2971.747401470878, 3362.7309815214726, 21788.0, 21973.78026219733, 26461.3384665383, 27401.31496712578, 27546.586801198, 2666.080202330193, 2666.6400035995084, 2692.582649689282, 2698.136596289188, 2817.29567608098, 263882.0, 264164.35835641396, 266492.0, 267876.0, 267910.0, 44550.55449445529, 58694.41305586975, 58782.82434351289, 58832.705836470544, 58909.41090589125, 61893.381066189664, 62084.379156208764, 62101.68949155316, 62111.0, 62126.37873621206, 16224.432144874872, 16815.07517086572, 16837.494875153767, 16845.241964111643, 16849.32602695903, 12977.221366718028, 13064.934675326562, 13066.281354525403, 13085.149465284727, 13118.147320424332, 17270.913645431945, 17406.39077632277, 17486.82513174852, 17559.736603950696, 17578.560535986704, 5744.569157313218, 5945.959457095078, 6232.0, 6335.239771227482, 6339.936600634027, 922.6078916460477, 968.3657204530903, 991.8561808537721, 994.2592768387598, 1024.6772266735954, 28224.43551129007, 28660.713392865804, 28916.277093072422, 29006.854965725037, 29017.274568135967, 1046.4610725476387, 1102.5369344875012, 1112.8163852964333, 1116.2744216259496, 1129.9604513842141, 14618.561443156723, 14645.48740794067, 14793.63015924589, 14813.777793333109, 14967.476138335105, 365882.0, 456648.0, 465955.0, 465997.0, 467085.0, 56681.43318566844, 56998.71500642553, 57002.714986424806, 57031.0, 57076.85846283134, 1327.6017194841509, 1356.7422189783908, 1357.1178733822896, 1488.047649504318, 1494.4321157959905, 27403.0, 27732.168034958984, 27821.132045345228, 28100.0, 28274.434511309246, 51152.48847511477, 52127.47872521226, 52206.0, 52326.0, 52406.95186096333, 357646.0, 370410.0, 370880.63992001, 371010.0, 371174.0, 27528.58707119435, 28905.0, 31662.208444789063, 37065.62934370622, 37411.0, 40359.375233837825, 40836.79581602073, 40968.0, 41703.58296416997, 41892.58107418887, 27769.583456248576, 34382.48426273658, 35153.64846351504, 35617.46573801343, 36073.81963090168, 34817.0, 35133.297334053685, 35418.8229058853, 35597.28805423929, 35728.64271357253, 28602.570961436013, 35201.82399087988, 35629.82185089058, 35660.821695892395, 35914.64085359113, 25924.481510370064, 29493.557596636496, 30683.693163068085, 30737.0, 30748.074259349396, 22537.098516059174, 22750.0, 22977.88511057434, 23222.3033309001, 23312.766872331737], "value": 18485.479486051638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [359.699650791589, 398.03278034376456, 401.5282043598772, 402.4807997682989, 405.7687118342546, 16553.66892662146, 18745.812541874577, 19238.615227695438, 19261.903690481544, 21295.14819407226, 19722.90138549309, 20396.082176302065, 20567.38297851063, 20618.896905515452, 20905.85017824019, 5798.6230894991795, 5872.295324561059, 5937.287525496947, 6014.548908831834, 6036.305824830135, 5957.88084238315, 5981.0860524632135, 6021.337652858182, 6022.728977196037, 6030.09548567714, 5683.630564013326, 5980.581359304851, 6006.759729610811, 6006.8498287542725, 6018.9097163542365, 5854.97072514639, 5946.910796338058, 5987.9401205987815, 6004.12940123684, 6004.369541198177, 5206.427292997767, 5206.921896171561, 5241.42344342122, 5245.737713114329, 5256.395514515803, 12808.359582020861, 13232.669183270398, 13270.270135142564, 13278.601641950758, 24785.76071196433, 2039.8062184092487, 2215.0586000949693, 2305.873176975266, 2317.953640927172, 2463.4457247119435, 9437.528123593793, 10071.395716256975, 10089.445080520569, 10124.34191777532, 10156.94921525395, 1719.6732620802009, 1725.5944852959544, 1752.6932786762393, 1771.7785276840395, 1848.7504186934725, 95381.52309238478, 97632.99999999929, 100606.0, 101097.0, 101450.98549014489, 9846.507674616241, 9924.50377481123, 11113.722156946142, 11441.256318339281, 11519.963203311741, 14718.926405367905, 14743.631409214855, 14760.483383081755, 14811.407543698348, 14815.555533334018, 7515.661795219228, 8335.458195217292, 8446.788830279293, 8657.917760279965, 8757.781055473664, 7064.293570642997, 8519.551676214935, 8533.701320453754, 8560.229579337765, 8662.0, 4413.16149931512, 4494.685372023992, 4504.324351347259, 4568.223402021665, 4585.2892801615135, 1886.377495426506, 1950.9317173898846, 1958.8530860185542, 1970.4384250488542, 1988.6321030609383, 872.3326655108847, 920.9769755756159, 981.7496538382667, 983.8475036369374, 1027.6403258859489, 17975.0, 18178.00020998858, 19201.807981920283, 19575.80424195768, 20071.097619427976, 919.2599957034652, 945.5366870233536, 968.3947532792005, 978.574320170728, 987.6790043235977, 10674.519646615921, 10804.24370294072, 10808.97314755097, 10830.620928267474, 10868.67393978182, 553799.0, 599989.0, 600222.0, 624880.8755956191, 625062.0, 92162.0, 111286.0, 114193.28710069356, 116058.83941160649, 117731.0, 798.9121196668361, 1013.0730381700662, 1020.102309967239, 1032.2722480651123, 1044.5665049004579, 14452.709883103149, 17753.911230443766, 17860.55348616243, 17933.641327173646, 17951.640967180843, 49640.29135652194, 49774.75112624414, 50047.74976125096, 51057.46827595178, 51195.0, 63890.0, 66887.0, 70907.0, 71586.0, 73643.0, 7231.847140083538, 7571.886421703569, 7771.0, 7895.249951254622, 8036.758897233092, 11242.381669007957, 11696.239744416585, 11801.881981180079, 11925.642230733092, 12199.756004879675, 8805.779855503664, 10124.34191777532, 10411.427371494641, 10462.319949203278, 10560.524776384927, 9838.950805245928, 9981.201503879818, 10344.0, 10353.0, 10498.370097794157, 8844.866071428407, 10496.947515262375, 10704.30422022566, 10763.354198747787, 10889.455527223765, 7493.100827900686, 7626.389888808771, 7802.843943121219, 7879.881801772864, 8238.423310368216, 8128.105908349959, 10178.796424071625, 10398.272120951764, 10816.783664326827, 10857.402842843403], "value": 9100.032848960343, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1450.7461194291009, 1634.7547867819826, 1643.0962970366297, 1895.289266525053, 2002.6094911492264, 7818.609069546521, 8618.181272779084, 8840.204381605663, 8847.247983921374, 8849.95575022124, 8421.199986001335, 8631.136886311366, 8633.223009929105, 8637.697680581181, 8641.265492433151, 2427.5630386530415, 2465.827392082546, 2468.975375219291, 2469.394998225437, 2470.2465747946867, 2072.968905466419, 2401.9159329423474, 2453.9631905521433, 2457.741937096606, 2460.9753902460925, 2413.4569721812627, 2441.9511609767883, 2448.155386391692, 2450.8529488230765, 2456.5578195924727, 2151.4621344663833, 2357.669926210332, 2422.527607116613, 2433.3551608823677, 2445.730969593343, 2387.985106329803, 2410.4214988402655, 2422.382292515415, 2425.1390756281617, 2429.8542087474807, 8480.66077356905, 8620.051794302623, 8675.175858293453, 8691.782705432352, 8707.608157632858, 5200.843974680765, 5417.674939503642, 5424.213489044067, 5429.647072940246, 5444.020076386249, 3294.934101317984, 3409.1136304560746, 3442.1910850950007, 3449.896503104911, 3477.2871561329866, 943.0478908659078, 956.268454632207, 962.2638681408728, 973.732223638505, 989.0554520432969, 11565.132615053904, 11691.123165762518, 11703.0, 11704.53181872724, 11713.53145874164, 4644.883877903012, 4790.640701947368, 5026.974865125724, 5035.597152227813, 5069.670471419419, 8951.149640784115, 8956.731298061068, 9025.187733104052, 9025.63897444108, 9035.232005279624, 5291.629585929025, 5326.201069839555, 5328.147496400557, 5333.066713325153, 5335.839924802262, 4213.536510983789, 4432.623227025739, 4963.305137280783, 4994.175960966472, 5018.623603229699, 3717.981410092932, 3725.720570957189, 3766.7928263945746, 3805.9429108563395, 3822.847086116524, 1183.952641894332, 1198.233130796291, 1201.5133870782347, 1207.4988879615037, 1219.920705154162, 669.6015870557011, 805.2752522729473, 857.1214505132165, 871.4422769427445, 975.7999610079917, 5590.105583106765, 5681.4034526374135, 5783.450572195635, 5986.0, 6049.667268300198, 875.3303722652176, 886.8980067292345, 923.1414784250577, 930.8091841172543, 955.3742298794301, 3827.9043023924623, 3923.058465968147, 3958.9208215835524, 3989.4813674222164, 3993.520777506718, 10146.644867429604, 10671.786564268672, 10815.513301901434, 10925.945370273097, 10975.231733778559, 10760.838587421194, 10900.454977251264, 10920.945395272973, 10953.671389858317, 10976.780464390667, 7553.924460755432, 7620.428467864932, 7812.375009999187, 7997.480163789335, 8168.836623267502, 5708.600397972268, 5763.7982670606325, 5826.300843898759, 5830.5044071254415, 6006.849828754316, 9749.9025009749, 9935.254855885692, 9942.552585133542, 10029.54867031, 10077.949610251902, 9061.728148155566, 10116.797664046826, 10170.796584068425, 10285.331453455501, 10303.690889273334, 7212.4951253411755, 7549.0563679540055, 7587.772366829004, 7594.392448603988, 7601.7719468416035, 8174.877376839233, 8211.0, 8304.169583041648, 8314.87527687073, 8321.043080045873, 7259.6007219601315, 7458.73894413693, 7499.1750907399055, 7555.9244407555225, 7578.545287282781, 7291.234420385996, 7304.452166087458, 7365.7790266292095, 7500.474966752275, 7516.0, 6962.547434416747, 7037.507374483942, 7044.577325360495, 7052.400545953651, 7059.717611295696, 4683.812647494062, 4837.080954618611, 4844.612431005582, 4956.430010548833, 4980.302757614009, 4722.409698787652, 4765.8570242892765, 4768.880777980578, 4778.59381952524, 4872.951270487391], "value": 4549.362628837434, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [310.47994609029877, 317.9729473799627, 326.71902164138845, 327.9426100432425, 333.71800828300076, 4894.902101957959, 5201.063808514466, 5233.241180028894, 5239.816606418776, 5260.868478288045, 5167.379914410267, 5180.766865491052, 5181.689098654083, 5185.377754669445, 5194.922076168851, 3866.9365924370813, 3892.3577609694507, 3996.240714264295, 4073.5926407359175, 4093.4269202311702, 3979.7315530147825, 4054.553999060101, 4061.776602286873, 4073.714839961204, 4090.4477895484174, 3993.8402463901407, 4001.5398229203724, 4018.97990510047, 4019.9799001004953, 4036.414719865618, 3818.0, 3823.847046118152, 3866.1881004988986, 4071.470708807836, 4075.53131389891, 3112.937741245185, 3393.9830300848585, 3533.328667553182, 3537.4693795930557, 3552.982235088808, 4139.79301034947, 4433.312836510346, 4941.802327906879, 4956.3804524434445, 5081.28861959326, 4310.267254566731, 4359.280718681413, 4602.217623004098, 4615.676902616819, 4649.605118464481, 5185.611079169077, 5449.536789372908, 5473.67157970523, 5480.725963701799, 5500.4499550045075, 3257.755668324885, 3788.6969042476535, 3808.733388662795, 3811.104390468238, 3813.074397429308, 5256.7933560528045, 5643.971780141073, 5804.9419505805245, 5809.128630705386, 5864.70676466175, 3447.3622379859808, 3486.4770284457536, 3674.684948482439, 3708.0532720321507, 3866.2460820139804, 3911.6139155115357, 3918.9020274493364, 3924.2543916655745, 3925.9803700981884, 3931.5871833457645, 4233.343831706091, 4397.142557201315, 4545.772711364419, 4547.3406356078185, 4555.772211389417, 4846.376463884568, 5553.972230138824, 5555.8608836417125, 5593.46862048113, 5607.663540187602, 1230.5139469909514, 1234.9256147152034, 1425.4084554909803, 1433.175923843794, 1433.1902475101533, 891.8394688955921, 894.2443635128307, 905.4431524612316, 936.5364144748393, 944.2163004706077, 4370.672199585044, 4860.8784780380065, 5092.479222784746, 5151.201563757623, 5184.766685499161, 4764.6182607043565, 4949.876253093701, 4991.301217829507, 5559.583031272671, 5625.943740562624, 4254.340577210537, 4476.462824461086, 4994.650374473825, 5059.266406371055, 5126.256692779526, 3216.517522371663, 3228.047725920859, 3237.4460259075304, 3244.270039241152, 3271.378438096754, 6228.968855155696, 6412.8777463943625, 6413.390727880844, 6469.191351081115, 6495.512836537278, 5745.942540574624, 5758.654480731169, 6200.782972595937, 6247.937520624827, 6305.747770089145, 5463.781448742086, 6336.398042186077, 6372.458341041063, 6398.616083035033, 6405.519586030973, 5097.719625420637, 6036.9698151508965, 6094.268687757498, 6120.265568131675, 6144.170536977451, 5114.9232761507865, 5573.303337082864, 6030.0, 6040.909386359296, 6062.90905636407, 6148.907766383597, 6402.679866006588, 6404.967975160094, 6417.807465776034, 6425.0082474433075, 4059.3559608358664, 4059.6143366380147, 4075.6535694465115, 4083.612056854594, 4090.813664037396, 6084.39156084388, 6126.816195514141, 6128.32588415265, 6132.5707200495535, 6137.294211165773, 5443.4284400138995, 5625.746841392229, 5929.822105336847, 5934.43622855828, 6011.609245399035, 5162.0, 5340.439253878287, 5503.2020357048805, 5897.351291358033, 5932.762689492372, 4355.520892701864, 5709.34342550612, 5714.828270310018, 5722.74247658848, 5767.9134812977, 4949.82675606352, 5059.8495639352395, 5493.807716729895, 5496.7526461310135, 5545.3622833374675, 4590.77046147698, 5367.114426119647, 5385.299911011543, 5405.378381486022, 5460.453954604508], "value": 4161.061604047143, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.1476676304766, 693.9340762627548, 712.1063065852356, 743.546436673629, 757.939364850812, 14717.411303547871, 14824.110553366792, 14839.40642374305, 15126.621834454158, 15376.538703838904, 14097.647558811035, 14161.78757318641, 14189.503367382144, 14239.71520569588, 14282.3572939218, 706.8232941764559, 730.2660825869993, 891.3136884598855, 896.4038914122101, 898.0301274623424, 728.5191773429538, 732.904722386089, 733.7761982595306, 876.31647315094, 922.8154369126169, 960.265396971314, 965.3532133470592, 965.9130678238957, 969.0938972061145, 974.1768205866067, 958.2142643032716, 958.6261358070358, 959.472290240365, 960.2558017536395, 961.0101595356758, 553.1371061144628, 690.8169335126196, 708.0865683268578, 711.7295427737427, 714.9320814522612, 15063.54809355721, 15101.018433801768, 15109.47116850905, 15116.470923517623, 15131.621709457242, 12120.878791212062, 12309.446074926649, 12312.507499700001, 12641.747165056648, 12747.808782868264, 9844.3601165924, 9994.400335979864, 10188.643397481053, 10285.382877027327, 10471.0, 3382.678645528671, 3461.8961431157104, 3471.826408679581, 3529.699975502085, 3736.3834967230373, 78202.60898695527, 79302.0, 79816.0, 80059.0, 80479.0, 9213.95393023044, 9484.95257523708, 9508.477033763209, 9720.27097967641, 9801.411915285105, 21807.890960545414, 21862.781372186393, 21866.517363940355, 21907.561848762936, 22018.889905550368, 7403.5928023959195, 7454.068241469817, 7468.103827540621, 7513.624318784039, 7627.466077374642, 4019.41718450823, 4082.081531655354, 4115.52671442782, 4134.131832315187, 4343.652507799368, 3481.1819222482695, 3560.6973407260675, 3569.0, 3582.3730847102174, 3709.1283548366114, 636.8089573128043, 648.3225029843825, 655.8393193667582, 661.1207094564314, 669.7241754457668, 490.94845041270867, 555.6888142640134, 569.481771587853, 605.9182010428535, 616.1804799616589, 8193.0, 8269.669213231524, 8315.0, 8399.0, 8506.31949444043, 508.1970486631114, 610.2646311194999, 618.8050764009323, 624.4348864277836, 624.6015941176754, 3894.337962546374, 3927.0, 3955.881323560298, 3997.420374045747, 4040.252553277653, 97222.0, 106148.46925765321, 106369.0, 106926.0, 108757.45621271843, 79707.80438293431, 80920.59539702264, 81140.18859811444, 83333.58333208416, 83699.0, 67347.0, 67809.32190678129, 68015.31984680188, 68461.65769171221, 68706.65646671735, 6120.724567394389, 7811.843763124819, 7864.134945156143, 7969.681212751425, 7972.561509117054, 46100.0, 46126.769366152956, 46752.29871551997, 47391.289130663754, 47926.28110578414, 54927.72536137294, 57680.26959191231, 67426.66286668535, 67487.66256168688, 70030.0, 10912.508937097691, 11145.331280123219, 11341.716457088638, 11362.375069371265, 11546.76906461883, 11798.0, 11839.408029598659, 11910.464029118537, 12172.2696638202, 12490.87509124897, 11452.083833293484, 11530.827037594276, 11784.646460606196, 11832.881671183179, 12155.81766273489, 11457.885421146015, 11564.306141631529, 11597.594084207012, 11841.46713397882, 11856.466459009538, 11093.667189984313, 11172.441377931233, 11293.491792869176, 11330.716732081764, 11679.299242045505, 9669.226461883172, 9755.268354873271, 9908.0, 9908.405495670282, 10037.498125093862, 8429.915700842914, 9381.671641492516, 9431.0, 9849.852252216366, 9875.0], "value": 6887.581359289697, "unit": "score"}], "name": "dromaeo_css", "value": 7047.495738914359, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 108.0, 112.0, 113.0, 113.0, 113.0, 113.0, 104.0, 110.0], "value": 111.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 123.0, 145.0, 171.0, 122.0, 142.0, 141.0, 121.0, 173.0, 116.0], "value": 140.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 184.0, 186.0, 185.0, 184.0, 180.0, 182.0, 185.0, 185.0, 186.0], "value": 184.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 71.0, 89.0, 89.0, 56.0, 58.0, 58.0, 81.0, 102.0, 79.0], "value": 76.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 97.0, 135.0, 78.0, 79.0, 79.0, 124.0, 80.0, 77.0, 125.0], "value": 96.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 91.0, 88.0, 88.0, 93.0, 88.0, 89.0, 86.0], "value": 89.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 94.0, 92.0, 93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0], "value": 93.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 84.0, 83.0, 86.0, 85.0, 84.0, 84.0, 84.0, 87.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 60.0, 59.0, 58.0, 60.0, 60.0, 58.0, 77.0, 60.0, 58.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 64.0, 75.0, 73.0, 70.0, 70.0, 66.0, 68.0, 68.0, 68.0], "value": 68.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 78.0, 81.0, 83.0, 83.0, 82.0, 81.0, 83.0, 81.0, 82.0], "value": 81.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 140.0, 142.0, 136.0, 140.0, 136.0, 137.0, 142.0, 195.0, 157.0], "value": 146.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 198.0, 196.0, 202.0, 199.0, 209.0, 265.0, 219.0, 205.0, 191.0], "value": 207.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 70.0, 71.0, 70.0, 69.0, 70.0, 71.0, 70.0, 70.0], "value": 70.3, "unit": "score"}], "name": "kraken", "value": 1511.2, "alertThreshold": 2.0}]} 14:43:18 INFO - Return code: 0 14:43:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 14:43:18 ERROR - # TBPL SUCCESS # 14:43:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:43:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:43:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:43:18 INFO - 2016-04-27 14:43:18.206 screenresolution[1686:10406] starting screenresolution argv=screenresolution get 14:43:18 INFO - 2016-04-27 14:43:18.209 screenresolution[1686:10406] Display 0: 1600x1200x32@60 14:43:18 INFO - 2016-04-27 14:43:18.214 screenresolution[1687:10409] starting screenresolution argv=screenresolution list 14:43:18 INFO - Available Modes on Display 0 14:43:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:43:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:43:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:43:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:43:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:43:18 INFO - Intel Iris: 14:43:18 INFO - Chipset Model: Intel Iris 14:43:18 INFO - Type: GPU 14:43:18 INFO - Bus: Built-In 14:43:18 INFO - VRAM (Dynamic, Max): 1536 MB 14:43:18 INFO - Vendor: Intel (0x8086) 14:43:18 INFO - Device ID: 0x0a2e 14:43:18 INFO - Revision ID: 0x0009 14:43:18 INFO - Displays: 14:43:18 INFO - Display: 14:43:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:43:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:43:18 INFO - Display Serial Number: 1600x1200 60 14:43:18 INFO - Main Display: Yes 14:43:18 INFO - Mirror: Off 14:43:18 INFO - Online: Yes 14:43:18 INFO - Rotation: Supported 14:43:18 INFO - Return code: 0 14:43:18 INFO - Running post-action listener: _resource_record_post_action 14:43:18 INFO - Running post-run listener: _resource_record_post_run 14:43:19 INFO - Total resource usage - Wall time: 1209s; CPU: 53.0%; Read bytes: 32435712; Write bytes: 696062976; Read time: 323; Write time: 1979 14:43:19 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 389184000; Write bytes: 382396416; Read time: 29786; Write time: 678 14:43:19 INFO - run-tests - Wall time: 1176s; CPU: 53.0%; Read bytes: 30645760; Write bytes: 298028032; Read time: 304; Write time: 1264 14:43:19 INFO - Running post-run listener: _upload_blobber_files 14:43:19 INFO - Blob upload gear active. 14:43:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:19 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 14:43:19 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:43:19 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:43:19 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:43:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:43:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:21 INFO - (blobuploader) - INFO - Done attempting. 14:43:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:43:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:43:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:22 INFO - (blobuploader) - INFO - Done attempting. 14:43:22 INFO - (blobuploader) - INFO - Iteration through files over. 14:43:22 INFO - Return code: 0 14:43:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:43:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:43:22 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4734dff05976a906918e8b9ce3376cf4ffbe4d265d63018e9514fe15b9f06d0090eead50cbefd11c708ddb0b8c1d428537781823bcecc4c22ae709d90085e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab806d5b8e592d654daee46f30c8b2ce48b0c9395a2b0ffb65aa1f70e2e09a81370a61c464067b0943e8710da2397c357ab348d86844bfb299dcd88fee531643"} 14:43:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:43:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:43:22 INFO - Contents: 14:43:22 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4734dff05976a906918e8b9ce3376cf4ffbe4d265d63018e9514fe15b9f06d0090eead50cbefd11c708ddb0b8c1d428537781823bcecc4c22ae709d90085e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab806d5b8e592d654daee46f30c8b2ce48b0c9395a2b0ffb65aa1f70e2e09a81370a61c464067b0943e8710da2397c357ab348d86844bfb299dcd88fee531643"} 14:43:22 INFO - Running post-run listener: copy_logs_to_upload_dir 14:43:22 INFO - Copying logs to upload dir... 14:43:22 INFO - mkdir: /builds/slave/test/build/upload/logs 14:43:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1605.934822 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 26 mins, 45 secs) (at 2016-04-27 14:43:22.319425) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 14:43:22.322556) ========= 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: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.psVdi4XHuV/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test/properties RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4734dff05976a906918e8b9ce3376cf4ffbe4d265d63018e9514fe15b9f06d0090eead50cbefd11c708ddb0b8c1d428537781823bcecc4c22ae709d90085e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab806d5b8e592d654daee46f30c8b2ce48b0c9395a2b0ffb65aa1f70e2e09a81370a61c464067b0943e8710da2397c357ab348d86844bfb299dcd88fee531643"} build_url:https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011015 build_url: 'https://queue.taskcluster.net/v1/task/LwDKtZhTTlmR74TLYdQCNw/artifacts/public/build/firefox-49.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f4734dff05976a906918e8b9ce3376cf4ffbe4d265d63018e9514fe15b9f06d0090eead50cbefd11c708ddb0b8c1d428537781823bcecc4c22ae709d90085e40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab806d5b8e592d654daee46f30c8b2ce48b0c9395a2b0ffb65aa1f70e2e09a81370a61c464067b0943e8710da2397c357ab348d86844bfb299dcd88fee531643"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 14:43:22.355693) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:43:22.356012) ========= rm -f oauth.txt in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['rm', '-f', 'oauth.txt'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.psVdi4XHuV/Render GIT_SHARE_BASE_DIR=/builds/git-shared HG_SHARE_BASE_DIR=/builds/hg-shared HOME=/Users/cltbld IDLEIZER_DISABLE_SHUTDOWN=true LOGNAME=cltbld MOZ_CRASHREPORTER_NO_REPORT=1 MOZ_NO_REMOTE=1 NO_EM_RESTART=1 PAGER=/bin/cat PATH=/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11 PWD=/builds/slave/test RUNNER_CONFIG_CMD=/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg SHELL=/bin/bash SSH_AUTH_SOCK=/private/tmp/com.apple.launchd.EGbxDroWMo/Listeners TMPDIR=/var/folders/8m/g8ffl9g56c9dzc7qx0hn0b_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False program finished with exit code 0 elapsedTime=0.004989 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 14:43:22.403768) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 14:43:22.404085) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 14:43:22.404435) ========= ========= Total master_lag: 0.15 =========