builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0012 starttime: 1461753627.19 results: success (0) buildid: 20160425062348 builduid: abe90fc74b164da98c10377025c22763 revision: 8551b253f4061db31e4be7829c2f70c2610caf42 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.191079) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.191535) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.191820) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.211302) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.211634) ========= 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.AksNifb2mC/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.Hj1VBL1G3h/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:40:27-- 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% 161M=0s 2016-04-27 03:40:27 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.107956 ========= master_lag: 0.47 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.790354) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.790874) ========= 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.AksNifb2mC/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.Hj1VBL1G3h/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.027311 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.836095) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:27.836642) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8551b253f4061db31e4be7829c2f70c2610caf42 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 8551b253f4061db31e4be7829c2f70c2610caf42 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AksNifb2mC/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.Hj1VBL1G3h/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:40:27,914 truncating revision to first 12 chars 2016-04-27 03:40:27,914 Setting DEBUG logging. 2016-04-27 03:40:27,914 attempt 1/10 2016-04-27 03:40:27,914 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/8551b253f406?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-04-27 03:40:28,560 unpacking tar archive at: mozilla-aurora-8551b253f406/testing/mozharness/ program finished with exit code 0 elapsedTime=0.892523 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:28.747498) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:28.747820) ========= script_repo_revision: 8551b253f4061db31e4be7829c2f70c2610caf42 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:28.748217) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:28.748492) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-04-27 03:40:28.761955) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-27 03:40:28.762362) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.AksNifb2mC/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.Hj1VBL1G3h/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:40:28 INFO - MultiFileLogger online at 20160427 03:40:28 in /builds/slave/test 03:40:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 03:40:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:40:28 INFO - {'append_to_log': False, 03:40:28 INFO - 'base_work_dir': '/builds/slave/test', 03:40:28 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 03:40:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:40:28 INFO - 'branch': 'Mozilla-Aurora', 03:40:28 INFO - 'buildbot_json_path': 'buildprops.json', 03:40:28 INFO - 'config_files': ('talos/mac_config.py',), 03:40:28 INFO - 'default_actions': ('clobber', 03:40:28 INFO - 'read-buildbot-config', 03:40:28 INFO - 'download-and-extract', 03:40:28 INFO - 'populate-webroot', 03:40:28 INFO - 'create-virtualenv', 03:40:28 INFO - 'install', 03:40:28 INFO - 'run-tests'), 03:40:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:40:28 INFO - 'download_minidump_stackwalk': True, 03:40:28 INFO - 'download_symbols': 'ondemand', 03:40:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:40:28 INFO - 'tooltool.py': '/tools/tooltool.py', 03:40:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:40:28 INFO - '/tools/misc-python/virtualenv.py')}, 03:40:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:40:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:40:28 INFO - 'installer_path': 'installer.exe', 03:40:28 INFO - 'log_level': 'info', 03:40:28 INFO - 'log_name': 'talos', 03:40:28 INFO - 'log_to_console': True, 03:40:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:40:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:40:28 INFO - 'opt_config_files': (), 03:40:28 INFO - 'pip_index': False, 03:40:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:40:28 INFO - 'cmd': ('bash', 03:40:28 INFO - '-c', 03:40:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:40:28 INFO - 'enabled': True, 03:40:28 INFO - 'halt_on_failure': False, 03:40:28 INFO - 'name': 'check_screen_resolution'},), 03:40:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:40:28 INFO - 'cmd': ('bash', 03:40:28 INFO - '-c', 03:40:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:40:28 INFO - 'enabled': True, 03:40:28 INFO - 'halt_on_failure': False, 03:40:28 INFO - 'name': 'check_screen_resolution'},), 03:40:28 INFO - 'run_cmd_checks_enabled': True, 03:40:28 INFO - 'sps_profile': False, 03:40:28 INFO - 'sps_profile_interval': 0, 03:40:28 INFO - 'suite': 'dromaeojs-e10s', 03:40:28 INFO - 'system_bits': '32', 03:40:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:40:28 INFO - 'title': 't-yosemite-r7-0012', 03:40:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:40:28 INFO - 'use_talos_json': True, 03:40:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:40:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:40:28 INFO - 'work_dir': 'build'} 03:40:28 INFO - ##### 03:40:28 INFO - ##### Running clobber step. 03:40:28 INFO - ##### 03:40:28 INFO - Running pre-action listener: _resource_record_pre_action 03:40:28 INFO - Running main action method: clobber 03:40:28 INFO - rmtree: /builds/slave/test/build 03:40:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:40:30 INFO - Running post-action listener: _resource_record_post_action 03:40:30 INFO - ##### 03:40:30 INFO - ##### Running read-buildbot-config step. 03:40:30 INFO - ##### 03:40:30 INFO - Running pre-action listener: _resource_record_pre_action 03:40:30 INFO - Running main action method: read_buildbot_config 03:40:30 INFO - Using buildbot properties: 03:40:30 INFO - { 03:40:30 INFO - "project": "", 03:40:30 INFO - "product": "firefox", 03:40:30 INFO - "script_repo_revision": "production", 03:40:30 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 03:40:30 INFO - "repository": "", 03:40:30 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 03:40:30 INFO - "buildid": "20160425062348", 03:40:30 INFO - "pgo_build": "False", 03:40:30 INFO - "basedir": "/builds/slave/test", 03:40:30 INFO - "buildnumber": 299, 03:40:30 INFO - "slavename": "t-yosemite-r7-0012", 03:40:30 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:40:30 INFO - "platform": "yosemite_r7", 03:40:30 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 03:40:30 INFO - "branch": "mozilla-aurora", 03:40:30 INFO - "repo_path": "releases/mozilla-aurora", 03:40:30 INFO - "revision": "8551b253f4061db31e4be7829c2f70c2610caf42", 03:40:30 INFO - "stage_platform": "macosx64", 03:40:30 INFO - "builduid": "abe90fc74b164da98c10377025c22763", 03:40:30 INFO - "slavebuilddir": "test" 03:40:30 INFO - } 03:40:30 INFO - Found installer url https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg. 03:40:30 INFO - Running post-action listener: _resource_record_post_action 03:40:30 INFO - ##### 03:40:30 INFO - ##### Running download-and-extract step. 03:40:30 INFO - ##### 03:40:30 INFO - Running pre-action listener: _resource_record_pre_action 03:40:30 INFO - Running main action method: download_and_extract 03:40:30 INFO - mkdir: /builds/slave/test/build/tests 03:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:30 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 03:40:30 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:40:30 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json 03:40:30 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:40:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json'}, attempt #1 03:40:31 INFO - Downloaded 1277 bytes. 03:40:31 INFO - Reading from file /builds/slave/test/build/firefox-47.0a2.en-US.mac.test_packages.json 03:40:31 INFO - Using the following test package requirements: 03:40:31 INFO - {u'common': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:40:31 INFO - u'cppunittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.cppunittest.tests.zip'], 03:40:31 INFO - u'gtest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.gtest.tests.zip'], 03:40:31 INFO - u'jittest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'jsshell-mac.zip'], 03:40:31 INFO - u'mochitest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.mochitest.tests.zip'], 03:40:31 INFO - u'mozbase': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:40:31 INFO - u'reftest': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.reftest.tests.zip'], 03:40:31 INFO - u'talos': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.talos.tests.zip'], 03:40:31 INFO - u'web-platform': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.web-platform.tests.zip'], 03:40:31 INFO - u'webapprt': [u'firefox-47.0a2.en-US.mac.common.tests.zip'], 03:40:31 INFO - u'xpcshell': [u'firefox-47.0a2.en-US.mac.common.tests.zip', 03:40:31 INFO - u'firefox-47.0a2.en-US.mac.xpcshell.tests.zip']} 03:40:31 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip'] for test suite category: common 03:40:31 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')]} 03:40:31 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:40:31 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:40:31 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:40:31 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:40:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:40:33 INFO - Downloaded 17919011 bytes. 03:40:33 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:33 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:40:36 INFO - Return code: 0 03:40:36 INFO - Downloading packages: [u'firefox-47.0a2.en-US.mac.common.tests.zip', u'firefox-47.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 03:40:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:36 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 03:40:36 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:40:36 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:40:36 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip 03:40:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip'}, attempt #1 03:40:38 INFO - Downloaded 17919011 bytes. 03:40:38 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:38 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 03:40:41 INFO - Return code: 0 03:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:41 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 03:40:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:40:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:40:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip 03:40:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip'}, attempt #1 03:40:43 INFO - Downloaded 10845194 bytes. 03:40:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 03:40:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-47.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 03:40:43 INFO - Return code: 0 03:40: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')]} 03:40:43 INFO - https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 03:40:43 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:40:43 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:40:43 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg 03:40:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg'}, attempt #1 03:40:47 INFO - Downloaded 115767664 bytes. 03:40:47 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:40:47 INFO - mkdir: /builds/slave/test/properties 03:40:47 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:40:47 INFO - Writing to file /builds/slave/test/properties/build_url 03:40:47 INFO - Contents: 03:40:47 INFO - build_url:https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg 03:40:48 INFO - Running post-action listener: _resource_record_post_action 03:40:48 INFO - Running post-action listener: set_extra_try_arguments 03:40:48 INFO - ##### 03:40:48 INFO - ##### Running populate-webroot step. 03:40:48 INFO - ##### 03:40:48 INFO - Running pre-action listener: _resource_record_pre_action 03:40:48 INFO - Running main action method: populate_webroot 03:40:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:40:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:40:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:40:48 INFO - '--tppagecycles', 03:40:48 INFO - '7'], 03:40:48 INFO - 'tests': ['tsvgm']}}, 03:40:48 INFO - 'suites': {'chromez': {'tests': ['tresize', 'tcanvasmark']}, 03:40:48 INFO - 'chromez-e10s': {'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:40:48 INFO - 'dromaeojs': {'tests': ['dromaeo_css', 'kraken']}, 03:40:48 INFO - 'dromaeojs-e10s': {'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:40:48 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:40:48 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:40:48 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'tests': ['damp', 'tps']}, 03:40:48 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['damp', 'tps']}, 03:40:48 INFO - 'g3': {'tests': ['dromaeo_dom']}, 03:40:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:40:48 INFO - 'other': {'tests': ['a11yr', 03:40:48 INFO - 'ts_paint', 03:40:48 INFO - 'tpaint', 03:40:48 INFO - 'sessionrestore', 03:40:48 INFO - 'sessionrestore_no_auto_restore', 03:40:48 INFO - 'tabpaint']}, 03:40:48 INFO - 'other-e10s': {'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['a11yr', 03:40:48 INFO - 'ts_paint', 03:40:48 INFO - 'tpaint', 03:40:48 INFO - 'sessionrestore', 03:40:48 INFO - 'sessionrestore_no_auto_restore', 03:40:48 INFO - 'tabpaint']}, 03:40:48 INFO - 'other-e10s_l64': {'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['a11yr', 03:40:48 INFO - 'ts_paint', 03:40:48 INFO - 'tpaint', 03:40:48 INFO - 'sessionrestore', 03:40:48 INFO - 'sessionrestore_no_auto_restore', 03:40:48 INFO - 'tabpaint']}, 03:40:48 INFO - 'other-e10s_nol64': {'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['a11yr', 03:40:48 INFO - 'ts_paint', 03:40:48 INFO - 'tpaint', 03:40:48 INFO - 'sessionrestore', 03:40:48 INFO - 'sessionrestore_no_auto_restore', 03:40:48 INFO - 'tabpaint']}, 03:40:48 INFO - 'other_l64': {'tests': ['a11yr', 03:40:48 INFO - 'ts_paint', 03:40:48 INFO - 'tpaint', 03:40:48 INFO - 'sessionrestore', 03:40:48 INFO - 'sessionrestore_no_auto_restore', 03:40:48 INFO - 'tabpaint']}, 03:40:48 INFO - 'other_nol64': {'tests': ['a11yr', 03:40:48 INFO - 'ts_paint', 03:40:48 INFO - 'tpaint', 03:40:48 INFO - 'sessionrestore', 03:40:48 INFO - 'sessionrestore_no_auto_restore', 03:40:48 INFO - 'tabpaint']}, 03:40:48 INFO - 'svgr': {'tests': ['tsvgx', 03:40:48 INFO - 'tsvgr_opacity', 03:40:48 INFO - 'tart', 03:40:48 INFO - 'tscrollx', 03:40:48 INFO - 'cart']}, 03:40:48 INFO - 'svgr-e10s': {'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['tsvgx', 03:40:48 INFO - 'tsvgr_opacity', 03:40:48 INFO - 'tart', 03:40:48 INFO - 'tscrollx', 03:40:48 INFO - 'cart']}, 03:40:48 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'tests': ['tp5o']}, 03:40:48 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'talos_options': ['--e10s'], 03:40:48 INFO - 'tests': ['tp5o']}, 03:40:48 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'talos_options': ['--xperf_path', 03:40:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:40:48 INFO - 'tests': ['tp5n']}, 03:40:48 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:40:48 INFO - 'talos_options': ['--e10s', 03:40:48 INFO - '--xperf_path', 03:40:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:40:48 INFO - 'tests': ['tp5n']}}, 03:40:48 INFO - 'talos.zip': {'path': '', 03:40:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:40:48 INFO - Running post-action listener: _resource_record_post_action 03:40:48 INFO - ##### 03:40:48 INFO - ##### Running create-virtualenv step. 03:40:48 INFO - ##### 03:40:48 INFO - Running pre-action listener: _resource_record_pre_action 03:40:48 INFO - Running main action method: create_virtualenv 03:40:48 INFO - Creating virtualenv /builds/slave/test/build/venv 03:40:48 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 03:40:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:40:48 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:40:48 INFO - Using real prefix '/tools/python27' 03:40:48 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:40:49 INFO - Installing distribute.............................................................................................................................................................................................done. 03:40:52 INFO - Installing pip.................done. 03:40:52 INFO - Return code: 0 03:40:52 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:52 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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:40:52 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 03:40:52 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 03:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:52 INFO - 'HOME': '/Users/cltbld', 03:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:52 INFO - 'LOGNAME': 'cltbld', 03:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:52 INFO - 'MOZ_NO_REMOTE': '1', 03:40:52 INFO - 'NO_EM_RESTART': '1', 03:40:52 INFO - 'PAGER': '/bin/cat', 03:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:52 INFO - 'PWD': '/builds/slave/test', 03:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:52 INFO - 'SHELL': '/bin/bash', 03:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:40:52 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:52 INFO - 'USER': 'cltbld', 03:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:52 INFO - 'XPC_FLAGS': '0x0', 03:40:52 INFO - 'XPC_SERVICE_NAME': '0', 03:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:52 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 03:40:52 INFO - Cleaning up... 03:40:52 INFO - Return code: 0 03:40:52 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 03:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:52 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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:40:52 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 03:40:52 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 03:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:52 INFO - 'HOME': '/Users/cltbld', 03:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:52 INFO - 'LOGNAME': 'cltbld', 03:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:52 INFO - 'MOZ_NO_REMOTE': '1', 03:40:52 INFO - 'NO_EM_RESTART': '1', 03:40:52 INFO - 'PAGER': '/bin/cat', 03:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:52 INFO - 'PWD': '/builds/slave/test', 03:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:52 INFO - 'SHELL': '/bin/bash', 03:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:40:52 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:52 INFO - 'USER': 'cltbld', 03:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:52 INFO - 'XPC_FLAGS': '0x0', 03:40:52 INFO - 'XPC_SERVICE_NAME': '0', 03:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:52 INFO - Downloading/unpacking psutil>=0.7.1 03:40:52 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 03:40:52 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 03:40:52 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 03:40:52 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 03:40:52 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 03:40:52 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 03:40:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:40:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:55 INFO - Installing collected packages: psutil 03:40:55 INFO - Running setup.py install for psutil 03:40:55 INFO - building 'psutil._psutil_osx' extension 03:40:55 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 03:40:56 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 03:40:56 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 03:40:56 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 03:40:56 INFO - building 'psutil._psutil_posix' extension 03:40:56 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 03:40:56 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:40:56 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:40:56 INFO - ^ 03:40:56 INFO - 1 warning generated. 03:40:56 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 03:40:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:40:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:40:57 INFO - Successfully installed psutil 03:40:57 INFO - Cleaning up... 03:40:57 INFO - Return code: 0 03:40:57 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 03:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:40:57 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:40:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 03:40:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 03:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:40:57 INFO - 'HOME': '/Users/cltbld', 03:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:40:57 INFO - 'LOGNAME': 'cltbld', 03:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:40:57 INFO - 'MOZ_NO_REMOTE': '1', 03:40:57 INFO - 'NO_EM_RESTART': '1', 03:40:57 INFO - 'PAGER': '/bin/cat', 03:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:40:57 INFO - 'PWD': '/builds/slave/test', 03:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:40:57 INFO - 'SHELL': '/bin/bash', 03:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:40:57 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:40:57 INFO - 'USER': 'cltbld', 03:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:40:57 INFO - 'XPC_FLAGS': '0x0', 03:40:57 INFO - 'XPC_SERVICE_NAME': '0', 03:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:40:57 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 03:40: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 03:40: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 03:40: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 03:40: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 03:40: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 03:40: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 03:40:59 INFO - Downloading mozsystemmonitor-0.0.tar.gz 03:40:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:40:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 03:40:59 INFO - Installing collected packages: mozsystemmonitor 03:40:59 INFO - Running setup.py install for mozsystemmonitor 03:40:59 INFO - Successfully installed mozsystemmonitor 03:40:59 INFO - Cleaning up... 03:41:00 INFO - Return code: 0 03:41:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:41: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')]} 03:41:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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')]} 03:41:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41: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', '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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:41: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:41: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 blobuploader==1.2.4 03:41:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:00 INFO - 'HOME': '/Users/cltbld', 03:41:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:00 INFO - 'LOGNAME': 'cltbld', 03:41:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:00 INFO - 'MOZ_NO_REMOTE': '1', 03:41:00 INFO - 'NO_EM_RESTART': '1', 03:41:00 INFO - 'PAGER': '/bin/cat', 03:41:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:00 INFO - 'PWD': '/builds/slave/test', 03:41:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:00 INFO - 'SHELL': '/bin/bash', 03:41:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:00 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:00 INFO - 'USER': 'cltbld', 03:41:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:00 INFO - 'XPC_FLAGS': '0x0', 03:41:00 INFO - 'XPC_SERVICE_NAME': '0', 03:41:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:00 INFO - Downloading/unpacking blobuploader==1.2.4 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41:02 INFO - Downloading blobuploader-1.2.4.tar.gz 03:41:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:41:02 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:41:03 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41: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 03:41:03 INFO - Downloading docopt-0.6.1.tar.gz 03:41:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:41:03 INFO - Installing collected packages: blobuploader, requests, docopt 03:41:03 INFO - Running setup.py install for blobuploader 03:41:04 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:41:04 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:41:04 INFO - Running setup.py install for requests 03:41:04 INFO - Running setup.py install for docopt 03:41:04 INFO - Successfully installed blobuploader requests docopt 03:41:04 INFO - Cleaning up... 03:41:04 INFO - Return code: 0 03:41:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:04 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')]} 03:41:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:04 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')]} 03:41:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:04 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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:41:04 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 03:41:04 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 03:41:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:04 INFO - 'HOME': '/Users/cltbld', 03:41:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:04 INFO - 'LOGNAME': 'cltbld', 03:41:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:04 INFO - 'MOZ_NO_REMOTE': '1', 03:41:04 INFO - 'NO_EM_RESTART': '1', 03:41:04 INFO - 'PAGER': '/bin/cat', 03:41:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:04 INFO - 'PWD': '/builds/slave/test', 03:41:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:04 INFO - 'SHELL': '/bin/bash', 03:41:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:04 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:04 INFO - 'USER': 'cltbld', 03:41:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:04 INFO - 'XPC_FLAGS': '0x0', 03:41:04 INFO - 'XPC_SERVICE_NAME': '0', 03:41:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-pHqjKI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-kI_7Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-OvP0db-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-UPAw99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-pvFr5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-oZAft4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-ggOvjK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-XEirQw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:05 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-xdm7tR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-cdLEUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-6N_Ljy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-BqDhiK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-0C5wZe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-AEQCov-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-q045xY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-WD2BYZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:06 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-T0dXPH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:06 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:41:06 INFO - Running setup.py install for manifestparser 03:41:06 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:41:07 INFO - Running setup.py install for mozcrash 03:41:07 INFO - Running setup.py install for mozdebug 03:41:07 INFO - Running setup.py install for mozdevice 03:41:07 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:41:07 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:41:07 INFO - Running setup.py install for mozfile 03:41:07 INFO - Running setup.py install for mozhttpd 03:41:07 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:41:07 INFO - Running setup.py install for mozinfo 03:41:07 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:41:07 INFO - Running setup.py install for mozInstall 03:41:08 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Running setup.py install for mozleak 03:41:08 INFO - Running setup.py install for mozlog 03:41:08 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Running setup.py install for moznetwork 03:41:08 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Running setup.py install for mozprocess 03:41:08 INFO - Running setup.py install for mozprofile 03:41:08 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Running setup.py install for mozrunner 03:41:08 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:41:08 INFO - Running setup.py install for mozscreenshot 03:41:09 INFO - Running setup.py install for moztest 03:41:09 INFO - Running setup.py install for mozversion 03:41:09 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:41:09 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:41:09 INFO - Cleaning up... 03:41:09 INFO - Return code: 0 03:41:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:09 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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:41:09 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 03:41:09 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 03:41:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:09 INFO - 'HOME': '/Users/cltbld', 03:41:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:09 INFO - 'LOGNAME': 'cltbld', 03:41:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:09 INFO - 'MOZ_NO_REMOTE': '1', 03:41:09 INFO - 'NO_EM_RESTART': '1', 03:41:09 INFO - 'PAGER': '/bin/cat', 03:41:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:09 INFO - 'PWD': '/builds/slave/test', 03:41:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:09 INFO - 'SHELL': '/bin/bash', 03:41:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:09 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:09 INFO - 'USER': 'cltbld', 03:41:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:09 INFO - 'XPC_FLAGS': '0x0', 03:41:09 INFO - 'XPC_SERVICE_NAME': '0', 03:41:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:41:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-i0aILJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:41:09 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)) 03:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:41:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-2Jb1wf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:41:09 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)) 03:41:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:41:09 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-8NU9h7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-wkerdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-FvGqhf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-qwxptn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-5vSQmK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-09MVUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-O9wGPY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-2E8Abq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-sUH4ME-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-kBsDk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:41:10 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)) 03:41:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:41:10 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-hg961U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:41:11 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)) 03:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:41:11 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-zpXwNu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:41:11 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)) 03:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:11 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-stmeIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:41:11 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)) 03:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:41:11 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-lXo4k4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:41:11 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)) 03:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:41:11 INFO - Running setup.py (path:/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/pip-d3J2_n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:41:11 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)) 03:41:11 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)) 03:41:11 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)) 03:41:11 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)) 03:41:11 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)) 03:41:11 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)) 03:41:11 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:13 INFO - Downloading blessings-1.6.tar.gz 03:41:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:41:14 INFO - Installing collected packages: blessings 03:41:14 INFO - Running setup.py install for blessings 03:41:14 INFO - Successfully installed blessings 03:41:14 INFO - Cleaning up... 03:41:14 INFO - Return code: 0 03:41:14 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:41:14 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:14 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:14 INFO - Reading from file tmpfile_stdout 03:41:14 INFO - Current package versions: 03:41:14 INFO - blessings == 1.6 03:41:14 INFO - blobuploader == 1.2.4 03:41:14 INFO - docopt == 0.6.1 03:41:14 INFO - manifestparser == 1.1 03:41:14 INFO - mozInstall == 1.12 03:41:14 INFO - mozcrash == 0.17 03:41:14 INFO - mozdebug == 0.1 03:41:14 INFO - mozdevice == 0.48 03:41:14 INFO - mozfile == 1.2 03:41:14 INFO - mozhttpd == 0.7 03:41:14 INFO - mozinfo == 0.9 03:41:14 INFO - mozleak == 0.1 03:41:14 INFO - mozlog == 3.1 03:41:14 INFO - moznetwork == 0.27 03:41:14 INFO - mozprocess == 0.22 03:41:14 INFO - mozprofile == 0.28 03:41:14 INFO - mozrunner == 6.11 03:41:14 INFO - mozscreenshot == 0.1 03:41:14 INFO - mozsystemmonitor == 0.0 03:41:14 INFO - moztest == 0.7 03:41:14 INFO - mozversion == 1.4 03:41:14 INFO - psutil == 3.1.1 03:41:14 INFO - requests == 1.2.3 03:41:14 INFO - wsgiref == 0.1.2 03:41:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:14 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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:41:14 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 03:41:14 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 03:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:14 INFO - 'HOME': '/Users/cltbld', 03:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:14 INFO - 'LOGNAME': 'cltbld', 03:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:14 INFO - 'MOZ_NO_REMOTE': '1', 03:41:14 INFO - 'NO_EM_RESTART': '1', 03:41:14 INFO - 'PAGER': '/bin/cat', 03:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:14 INFO - 'PWD': '/builds/slave/test', 03:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:14 INFO - 'SHELL': '/bin/bash', 03:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:14 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:14 INFO - 'USER': 'cltbld', 03:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:14 INFO - 'XPC_FLAGS': '0x0', 03:41:14 INFO - 'XPC_SERVICE_NAME': '0', 03:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 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)) 03:41:14 INFO - Cleaning up... 03:41:14 INFO - Return code: 0 03:41:14 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:14 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 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:41:14 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 03:41:14 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 03:41:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:14 INFO - 'HOME': '/Users/cltbld', 03:41:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:14 INFO - 'LOGNAME': 'cltbld', 03:41:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:14 INFO - 'MOZ_NO_REMOTE': '1', 03:41:14 INFO - 'NO_EM_RESTART': '1', 03:41:14 INFO - 'PAGER': '/bin/cat', 03:41:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:14 INFO - 'PWD': '/builds/slave/test', 03:41:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:14 INFO - 'SHELL': '/bin/bash', 03:41:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:14 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:14 INFO - 'USER': 'cltbld', 03:41:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:14 INFO - 'XPC_FLAGS': '0x0', 03:41:14 INFO - 'XPC_SERVICE_NAME': '0', 03:41:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:15 INFO - Downloading/unpacking jsonschema 03:41:15 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 03:41:15 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 03:41:15 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 03:41:15 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 03:41:15 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 03:41:15 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 03:41:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:41:17 INFO - Downloading/unpacking functools32 (from jsonschema) 03:41:17 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 03:41:17 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 03:41:17 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 03:41:17 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 03:41:17 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 03:41:17 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 03:41:17 INFO - Downloading functools32-3.2.3-2.tar.gz 03:41:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:41:18 INFO - warning: no files found matching '*.txt' 03:41:18 INFO - no previously-included directories found matching 'build' 03:41:18 INFO - no previously-included directories found matching 'dist' 03:41:18 INFO - no previously-included directories found matching '.git*' 03:41:18 INFO - Installing collected packages: jsonschema, functools32 03:41:18 INFO - Running setup.py install for functools32 03:41:18 INFO - warning: no files found matching '*.txt' 03:41:18 INFO - no previously-included directories found matching 'build' 03:41:18 INFO - no previously-included directories found matching 'dist' 03:41:18 INFO - no previously-included directories found matching '.git*' 03:41:18 INFO - Successfully installed jsonschema functools32 03:41:18 INFO - Cleaning up... 03:41:18 INFO - Return code: 0 03:41:18 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 03:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:41:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 03:41:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:41:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:41:18 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d9aba30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d9b24c8>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87ba55c800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, '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.AksNifb2mC/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.Hj1VBL1G3h/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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 03:41:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 03:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 03:41:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:18 INFO - 'HOME': '/Users/cltbld', 03:41:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:18 INFO - 'LOGNAME': 'cltbld', 03:41:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:18 INFO - 'MOZ_NO_REMOTE': '1', 03:41:18 INFO - 'NO_EM_RESTART': '1', 03:41:18 INFO - 'PAGER': '/bin/cat', 03:41:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:18 INFO - 'PWD': '/builds/slave/test', 03:41:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:18 INFO - 'SHELL': '/bin/bash', 03:41:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:18 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:18 INFO - 'USER': 'cltbld', 03:41:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:18 INFO - 'XPC_FLAGS': '0x0', 03:41:18 INFO - 'XPC_SERVICE_NAME': '0', 03:41:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:41:18 INFO - Downloading/unpacking flake8 03:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 03:41:21 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 03:41:21 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:41:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:41:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:41:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 03:41:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:41:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:41:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:41:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:41:22 INFO - no previously-included directories found matching 'docs/_build' 03:41:22 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 03:41:22 INFO - Running setup.py install for pep8 03:41:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 03:41:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 03:41:22 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 03:41:22 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 03:41:22 INFO - no previously-included directories found matching 'docs/_build' 03:41:22 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 03:41:22 INFO - Successfully installed flake8 mccabe pyflakes pep8 03:41:22 INFO - Cleaning up... 03:41:22 INFO - Return code: 0 03:41:22 INFO - Running post-action listener: _resource_record_post_action 03:41:22 INFO - Running post-action listener: _start_resource_monitoring 03:41:22 INFO - Starting resource monitoring. 03:41:22 INFO - ##### 03:41:22 INFO - ##### Running install step. 03:41:22 INFO - ##### 03:41:22 INFO - Running pre-action listener: _resource_record_pre_action 03:41:22 INFO - Running main action method: install 03:41:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:41:22 INFO - Reading from file tmpfile_stdout 03:41:22 INFO - Detecting whether we're running mozinstall >=1.0... 03:41:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:41:23 INFO - Reading from file tmpfile_stdout 03:41:23 INFO - Output received: 03:41:23 INFO - Usage: mozinstall [options] installer 03:41:23 INFO - Options: 03:41:23 INFO - -h, --help show this help message and exit 03:41:23 INFO - -d DEST, --destination=DEST 03:41:23 INFO - Directory to install application into. [default: 03:41:23 INFO - "/builds/slave/test"] 03:41:23 INFO - --app=APP Application being installed. [default: firefox] 03:41:23 INFO - mkdir: /builds/slave/test/build/application 03:41:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 03:41:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-47.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 03:41:55 INFO - Reading from file tmpfile_stdout 03:41:55 INFO - Output received: 03:41:55 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 03:41:55 INFO - Running post-action listener: _resource_record_post_action 03:41:55 INFO - ##### 03:41:55 INFO - ##### Running run-tests step. 03:41:55 INFO - ##### 03:41:55 INFO - Running pre-action listener: _resource_record_pre_action 03:41:55 INFO - Running main action method: run_tests 03:41:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:41:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:41:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:41:55 INFO - 2016-04-27 03:41:55.383 screenresolution[1638:7534] starting screenresolution argv=screenresolution get 03:41:55 INFO - 2016-04-27 03:41:55.386 screenresolution[1638:7534] Display 0: 1600x1200x32@60 03:41:55 INFO - 2016-04-27 03:41:55.392 screenresolution[1639:7536] starting screenresolution argv=screenresolution list 03:41:55 INFO - Available Modes on Display 0 03:41:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:41:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:41:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:41:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:41:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:41:55 INFO - Intel Iris: 03:41:55 INFO - Chipset Model: Intel Iris 03:41:55 INFO - Type: GPU 03:41:55 INFO - Bus: Built-In 03:41:55 INFO - VRAM (Dynamic, Max): 1536 MB 03:41:55 INFO - Vendor: Intel (0x8086) 03:41:55 INFO - Device ID: 0x0a2e 03:41:55 INFO - Revision ID: 0x0009 03:41:55 INFO - Displays: 03:41:55 INFO - Display: 03:41:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:41:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:41:55 INFO - Display Serial Number: 1600x1200 60 03:41:55 INFO - Main Display: Yes 03:41:55 INFO - Mirror: Off 03:41:55 INFO - Online: Yes 03:41:55 INFO - Rotation: Supported 03:41:55 INFO - Return code: 0 03:41:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:41:55 INFO - Python 2.7.3 03:41:55 INFO - Return code: 0 03:41:55 INFO - grabbing minidump binary from tooltool 03:41: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')]} 03:41:55 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 0x10d84fd30>, '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 0x10d6b3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d846b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:41:55 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 03:41:55 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 03:41:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:41:55 INFO - Return code: 0 03:41:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:41:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:41:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:41:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:41:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:41:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 03:41:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 03:41:56 INFO - Return code: 0 03:41:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 03:41:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0012 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 03:41:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.AksNifb2mC/Render', 03:41:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:41:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:41:56 INFO - 'HOME': '/Users/cltbld', 03:41:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:41:56 INFO - 'LOGNAME': 'cltbld', 03:41:56 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:41:56 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:41:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:41:56 INFO - 'MOZ_NO_REMOTE': '1', 03:41:56 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:41:56 INFO - 'NO_EM_RESTART': '1', 03:41:56 INFO - 'PAGER': '/bin/cat', 03:41:56 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:41:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:41:56 INFO - 'PWD': '/builds/slave/test', 03:41:56 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:41:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:41:56 INFO - 'SHELL': '/bin/bash', 03:41:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Hj1VBL1G3h/Listeners', 03:41:56 INFO - 'TMPDIR': '/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/', 03:41:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:41:56 INFO - 'USER': 'cltbld', 03:41:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:41:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:41:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:41:56 INFO - 'XPC_FLAGS': '0x0', 03:41:56 INFO - 'XPC_SERVICE_NAME': '0', 03:41:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:41:56 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0012', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 03:41:57 INFO - mozversion application_buildid: 20160425062348 03:41:57 INFO - mozversion application_changeset: 8551b253f4061db31e4be7829c2f70c2610caf42 03:41:57 INFO - mozversion application_display_name: FirefoxDeveloperEdition 03:41:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:41:57 INFO - mozversion application_name: Firefox 03:41:57 INFO - mozversion application_remotingname: firefox-dev 03:41:57 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:57 INFO - mozversion application_vendor: Mozilla 03:41:57 INFO - mozversion application_version: 47.0a2 03:41:57 INFO - mozversion platform_buildid: 20160425062348 03:41:57 INFO - mozversion platform_changeset: 8551b253f4061db31e4be7829c2f70c2610caf42 03:41:57 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 03:41:57 INFO - mozversion platform_version: 47.0a2 03:41:57 INFO - using testdate: 1461753717 03:41:57 INFO - actual date: 1461753717 03:41:57 INFO - starting webserver on 'localhost:49277' 03:41:57 INFO - SUITE-START | Running 2 tests 03:41:57 INFO - TEST-START | dromaeo_css 03:41:57 INFO - operating with platform_type : mac_ 03:41:57 INFO - Initialising browser for dromaeo_css test... 03:41:57 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmp6T3sGn/profile http://localhost:49277/getInfo.html) 03:41:59 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 03:41:59 INFO - PROCESS | 1657 | colorDepth:24 03:41:59 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 03:41:59 INFO - PROCESS | 1657 | __metrics 03:41:59 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 03:42:00 INFO - TEST-INFO | 1657: exit 0 03:42:00 INFO - Browser initialized. 03:42:00 INFO - Running cycle 1/1 for dromaeo_css test... 03:42:00 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmp6T3sGn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:42:12 INFO - PROCESS | 1660 | RSS: Main: 217833472 03:42:12 INFO - PROCESS | 1660 | 03:43:02 INFO - PROCESS | 1660 | 1461753782097 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] 03:45:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 03:45:14 INFO - PROCESS | 1660 | RSS: Main: 228630528 03:45:14 INFO - PROCESS | 1660 | 03:48:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 03:48:16 INFO - PROCESS | 1660 | RSS: Main: 240369664 03:48:16 INFO - PROCESS | 1660 | 03:51:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 03:51:19 INFO - PROCESS | 1660 | RSS: Main: 242294784 03:51:19 INFO - PROCESS | 1660 | 03:54:20 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 03:54:21 INFO - PROCESS | 1660 | RSS: Main: 244039680 03:54:21 INFO - PROCESS | 1660 | 03:57:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 03:57:24 INFO - PROCESS | 1660 | RSS: Main: 246824960 03:57:24 INFO - PROCESS | 1660 | 04:00:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 04:00:27 INFO - PROCESS | 1660 | RSS: Main: 248426496 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | __start_tp_report 04:00:27 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:00:27 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:00:27 INFO - PROCESS | 1660 | |i|pagename|runs| 04:00:27 INFO - PROCESS | 1660 | |0;dojo.html;3443.6039855416625;3499.615042345343;3510.3505851417503;3553.200529880777;3553.857845686173;5716.456936591027;5734.541236701064;5743.167240750094;5759.712014399284;5799.391063938289;5020.9497905020935;5745.856353591163;5763.682997435149;5800.245968024151;5809.099589563624;5135.5634771044415;5144.511271429208;5156.561692256163;5193.662411943231;5220.582353411718;5145.81989630363;5224.712640804754;5240.973795131038;5248.265242866002;5258.237555554461;5209.687418754868;5222.712750798704;5241.528262456378;5250.973745131269;5324.467553244664;5059.266406371074;5214.270002199695;5283.63014588979;5292.788288468457;5321.228421878806;5106.795728170869;5174.948250517485;5211.296474975868;5244.213367994793;5246.291750613656;4461.152381047623;5516.3380394352935;5621.634593751393;5661.886762264773;5679.772809087632;6112.541559383019;6131.969340153315;6170.167027451282;6184;6191.195144631214;5944.256967879015;6197.318294987548;6208.689565521706;6208.96895515524;6228.750849966042;4828.444728856191;5131.871703207413;5888.352281249016;5907.940920590825;5925.140854576062;6242.063690446378;6264.874702505925;6277.7175027123885;6293.244810622755;6295.37046295376;6020.668863212565;6226.470749986212;6228.408301211378;6240.219972503437;6250.218722659668;5251.343582052244;6159.630422174684;6199.659018753921;6248.094026366151;6274.058891166362;6187.876242475125;6205.410486003822;6212.968935155295;6221.377862213742;6232.968835155886;5435.219308470309;6119.173911521888;6159.476444502178;6233.470155036782;6241.095041218998;4875.561199492063;4913.48408417111;4917.164082106051;4930;4948.307236986825;4383.013821890049;4411.713238639478;4418.734875907456;4424.269995450714;4441.888952776142;4377.584129507692;4695.5539223773685;5733.971330143323;5884.029135192731;5912.320083190488;6134.436587033474;6137.254412462224;6192.628442293477;6226.6575338355915;6230.7819226326865;5336.706481143575;5775.27809023872;5779.479846813807;5790.247267855152;5790.507806836382;5311.097113490716;5397.379301380313;5429.809956651498;5436.130219164915;5463.262459567907;5347.572194224531;6275.34108918557;6284.591501552384;6290.874182516324;6313.337099604475;6192.411720886508;6230.127782110599;6249.78125765596;6258.405451482102;6297.685115744102;6185.8144255672405;6279.843003924939;6282.685865706605;6307.400796924285;6327.525435592257;6182.28903676083;6197.225346831646;6226.128342032029;6236;6267.4045965633195;5225.451327610698;6035.336113027475;6196.938030619636;6212.81361559154;6255.96872015637;5999.310079340931;6016.157737916607;6054.152418661479;6124.846878828065;6152.169457123231;6147.692615369303;6170.413810687977;6189.381061893775;6210.689465526615;6212.1303017578475;5977.850553736192;6013.488853447506;6070.362611925861;6086.908696369469;6099.695015249308;5966.224390829165;6125.264968203844;6162.383761623802;6164.506839452923;6190.0405437157315;6008.279006519246;6037.245344331958;6038.275406951194;6040.395960403925;6057.848553786191 04:00:27 INFO - PROCESS | 1660 | |1;ext.html;475.864378652084;526.2842534153552;602.9216201893754;630.4168644004295;674.9021391898174;37638.81180594097;38543.03642408941;38566.84299471013;38649.61350386495;38702.41946370803;33938.981830545126;40089.59910400895;40179;40273.597264027354;40357;9450.724152239429;9669.468179250138;9672.661456849011;9676.661316853912;9690.370125941827;9480.620775168985;9759.902400976005;9776.90223097767;9795.559199836025;9802.950985245065;9732.75668108296;9814.214862810994;9824.115829575338;9830.115289623933;9837.606495740198;9709.320347575673;9797.902020979805;9800.656977005841;9821.410715357066;9858.16205622523;9633.566489507919;9641.42151470914;9708.466034368106;9752.97593752653;9770.90229097707;33201.3359732804;39084.41373379402;39186;39198.21603567913;39490.60509394898;3299.637039925606;3351.4805205193234;3369.898903032913;3391.6777906098882;3960;28002.569043031282;28350.716492835014;28436.431271374662;28536;28550.857245713847;2788.442311537701;2801.1176479408923;2806.3545384561667;2811.12855014944;2860.67102283238;390789;400842;416678;416862;421174;48004.279935800994;48021.75989120032;52774.47225527695;64041.71916561736;64374;46921.5307846924;47232.7638361806;47316.7634161827;47946.04107917892;47948;16904;16993.91503042477;16997.150142492825;17104.657906841792;17127.229274682668;13626.454941802414;13677.110987785762;13721.725565488634;13819.170849749047;13881.236531990837;15373.615659608598;15403;15421.306041228172;15433.768493472608;15493.767593486107;5619.831405057855;6181.260337495751;6221.688915554204;6680.465562754968;6717.193936727526;908.6047569307373;1007.219404961161;1084.7017070305571;1100.9540936110727;1138.0610995928228;27102.72897271002;30290.242743931136;31245.375092498478;31777;31847.363052738816;1081.8160912644869;1094.7208461842338;1110.4003837927428;1112.955481780736;1208.601161616682;14879.776803347959;15113.3199006045;15148.448211392248;15231.96326520413;15315.617109572127;666172;680067;687310;688945;690029;57426.42573574211;59277.40722592805;59464;59650.403495964485;59667.40332596619;1458.1761304862507;1500.8274048484345;1538.438469958457;1548.9767653485208;1551.4957638767446;25729.742702572734;27361.726382735917;29995.550066749453;30350.241243969078;30703.385932281675;50776.23835642542;51327.743361282955;51474.74262628663;51481.22778158256;51497.7425112872;390298;510864;517442;519625;541929;33441.83279083687;33697.157571059935;33911.32177356489;34138.31723365568;34285;43147;43883;44054;44195;45551.08897821959;36377.454338185475;36389.90830275096;36509.45235821412;36775.44836827396;36932.44601331036;35914.820425897706;36156.63843361533;36372.63627363693;36469.27061458703;36493.635063649024;31519.896803611762;35608.64391356053;36568.817155914054;36688;36788.44817327688;24276.907539160427;24523;31134.22164445907;31476.527852082694;31535.842320788248;24399.51200976006;24407.633885492087;24534.87732561326;24932.252032439057;25043.624345634467 04:00:27 INFO - PROCESS | 1660 | |2;jquery.html;369.30754834684967;372.1552076785696;375.9699224062075;376.05234808283126;379.0920744816167;20115.295964641242;20685.68971465429;20699.896500517494;21682.89158554205;21753.99999999998;21094.050767715453;21191.68212476814;21434.678479822815;21469.463263418424;21494.570108597858;5785.3925337839555;5986.102084687288;5996.97001514994;6023.156758053876;6024.186734790791;4100.7334523255895;5133.1786914093755;5620.1288800235825;6023.337432882379;6033.456988871001;5964.552658550604;5983.910241346383;6024.668643224621;6033.607815491979;6057.51539876811;5844.824655260349;6538.215414150309;6552.246491653425;6559.048937904025;6559.27847936729;5735.827925162252;5790.652560846363;5799.927013502515;5802.883942321172;5831.825045248649;11911.880881191164;13810.65473363164;24237;24920.750792492025;25239.747602523923;2275.977240227593;2283.7259528856475;2752.587111933206;2838.914832555027;2867.641544806899;9742.220622350194;10094.848577271347;10278.948605257001;10315;10372.377657340583;1698.388580111159;1704.4886534039738;1752.71080271755;1760.3310741918117;1772.8670349723823;107619;116092.41953790262;122030.77969220372;122216;123431;10036.949815251022;11441.741408445065;11676.649700508999;11736.295822250693;11740.002099821611;14357.497487588093;14372.209528476034;14373.993820432679;14427.134371937716;14462.710745785025;9044.14080662336;9054.81890362189;9071.773205669911;9083.000869904305;9098.454092754324;9121;9233.261339092856;9247.306452016126;9283.535823208813;9334.159925606727;4521.299198624219;4603.401557797465;4611.723296602215;4612.400387949545;4617.960958784247;1705.6332888429047;1819.4450692538637;1869.906504674788;1928.6817675083735;1948.5448335097597;416.13444036404206;434.1512343368707;466.4099913609287;1024.6413755185624;1056.529844219315;11487.483063262172;11794;11922.403879805976;12166.330851803235;12262.20295680778;441.7415811750098;468.4168210577852;472.3858983321668;498.26505903791616;882.594006756899;8803.295736341077;10822.296550724177;11125.610603628835;11213.625980733008;11862.288262704093;560592;562168;568839;660297;674473;117920.41039794918;118829.40585297019;121563;124115;132199;990.9545429571726;1039.7608550033535;1056.941868197241;1087.0488322717622;1091.5906535049357;17508.737368939732;17837.375691850724;18005.72991405156;18148.455546333633;18476;53367.46632533625;54209.18686219789;54793.452065478836;56459.43540564542;56863.431365687466;66215.66892165509;80762.59618701869;83504;84533;85449;6578.210614726264;7336.853262934605;7929.167437418988;8247.95876020616;8472;9457.29070319715;11564.363959982282;12651.746965060831;13602.86397136016;13615.18308901469;11466.2546934449;11738.647840564796;11879.940600296943;11892.048636108917;11945.581904633296;11334.263272887565;11497.770044599229;11703.941480292544;11807.586734464252;11842.46708898119;11701.2979221247;11877.643670689893;11923.761524769283;11989.460474278503;12084.335361554848;8248.711295104642;8263.669453221804;8302.792430189294;8309.252167304972;8493.150684931457;7639.885401718868;9752.707418777449;11090.223684342018;12129.21160124589;12146.392680366123 04:00:27 INFO - PROCESS | 1660 | |3;mootools.html;1582.3828706804347;1685.469077240669;2032.8475364347669;2068.3588087692815;2125.075592117428;8371.079181290053;8667.873176487048;9249.35254532183;9431.481268530237;9482.525873706312;7932.6033698315;9254.213391861684;9354.719358419241;9370.390924589898;9411.152996230338;2542.084849454195;2578.626099215622;2580.8064395170345;2583.599542070982;2592;2118.724565806443;2538.7207407185197;2556.5526032944254;2563.910338106307;2599.4021375083744;2229.342344008521;2539.212844018351;2609.6085587161983;2615.1370047884247;2644.598362867683;2580.780633646133;2592.585186370181;2607.7131515533274;2615.8038147138946;2630.3818602628367;2586.301698541402;2590.4689538644534;2599.6620439342955;2604.8306860054035;2624.6981597116387;9397.107274808883;9401.96578376378;9426.622935082589;9472.952635236848;9493.762655933588;5120.513551212629;5304.840854774363;5825.504832089277;5853.9414605854245;5919.082542205965;3254.576905002358;3652.2695460907926;3741.28915506053;3768.7361884668085;3789.450529673182;1048.567323808764;1070.379180075562;1070.4701172919454;1079.6707004363666;1085.3325204998869;12576.874231257754;12602.432890519949;12773.29746863922;12779.23324600527;12779.61661150167;5107.540321371094;5115.7442127893455;5179.964007198575;5189.832287735305;5230.843074707765;10751.301165424222;10756.19328550362;10868.836967445495;10871.673849784362;10890.673279801618;5706.80026199081;5819.941800582024;5830.3586605473365;5844.240248767718;5851.502622277154;4643.907121857544;5019.3474848270225;5099.5410413063;5147.794088236428;5244.239585260116;4145.129522800185;4147.336426171858;4169.124483858423;4176.31090870003;4182.142660754538;1360.0615575253044;1374.6013656039836;1389.1526169036879;1392.8258967629047;1395.1001603965385;1379.4689044717857;1407.0150894373774;1416.0370947755418;1425.672095418039;1444.0469290268582;8624.913750862537;8850.291976641853;8907.51008694515;8915.153060459246;8921.821563568692;1157.8321143434323;1210.6186551236333;1266.9873301267053;1277.5975567696146;1278.2105052925979;6504.121943537656;6557.803265902031;6575.572587781779;6649.401553860079;6736.696848641823;11121.833172502418;11548.076153907667;11560.537578496765;11563.826542601699;11569.363684997237;11295.209335346446;11437.59968401102;11570.42147892602;11583.24708893936;11605.30368177912;8799.428037177691;8937.419067760575;9002.639894404281;9029.683961061462;9111.088891110836;8267.462614930011;8307.543085130375;8385.790355241168;8389.825424440462;8465.492070475791;9985.850212246676;10000.149987251165;10080.445575493419;10183.898161018295;10228.795424091328;10533;10565.9471702641;10810.67567972962;10839.241253112206;10856.022957933825;6779.966100169665;7210.495265331377;7365.594892280976;7442.106947166375;7568.167501574932;8662.653493860174;8725.127487251224;8734.737957861274;8764;8809.295256379346;7631.809204769926;7690.115636701627;7766.728164514441;7773.494722842997;7891.723789667334;7133.857322853618;7245.7826265212125;7547.05661792276;7582.772516824504;7730.7680769577;7549.811254718676;8193.631286591999;8212.219839115274;8239.876401854097;8241.175882411711;6977.616231107338;7251.746188883364;7384.4830861839155;7434.851302974019;7465.552066876005;6656.76701315471;7177.892331614926;7398.5942670892355;7580.128285247047;7583.924160758322 04:00:27 INFO - PROCESS | 1660 | |4;prototype.html;288.66659008844783;341.1284168948336;346.12603176978143;347.10793261318406;347.77916023325196;7041.225465198824;7077.64611769412;7077.964610176942;7107;7113.502054856162;7012.193597736262;7023.82440438988;7037.331453511908;7082.468814838882;7089.22018577956;4906.31311616374;4994.101061808873;5108.514691104339;5137.460566640505;5220.425753167148;5076.213186956028;5103.464136265694;5104.591632669396;5107.565856902168;5110.361204849394;5019.949800501985;5043.52086551777;5082.703910502817;5086.92369614456;5101.591872650179;4345.739735476708;5068.695878247298;5110.744462776846;5121.564667003309;5141.074606570815;4412.205802955466;4418.094290670405;4420.359047938063;4426.778661066966;4442.711223770477;6560.803175904731;6728.596284222962;6739.966300168517;6771.627560484171;6839.350261725128;5971.402859714038;6028.216331876872;6038.607490513103;6042.456178943916;6044.516438684938;6426.710798014101;7413.073365829271;7413.592252426114;7423.777286681408;7437.256274372574;4928.704277743311;4942.950570494321;4948.307236986825;4971.328870602459;4972.254161875711;7079.929200707978;7850.450468467209;7852.411069169835;7864.095629002681;7886.881696774553;4951.90096198074;4985;4989.650724449254;5008.824691135793;5015.749212539358;5106.106431374569;5108.131617625013;5114.181730923035;5124.30821839047;5146.536811686966;5946.43508866657;5954.225950626391;5975.462208401264;5991.221141251696;6012.218411606463;7434.1079070510805;7463.216362281991;7474.514156579805;7483.176850546435;7496.512726672749;1696.1180186303054;1713.4345665930214;1766.9823301766817;1773.4413659697154;1804.440623406748;1141.8686851012073;1152.5562658376418;1167.3229526874393;1168.1297433411992;1168.7253495428567;6872.175338959257;6873.106496155568;6875;6889.311068893071;6919;7713.65288562016;7717;7725.652345644459;7729.227077292226;7782.143964163937;6242.781502647476;6846.246912839583;6876.140482439695;6884.690188941509;6905.551139175937;3060.4185204811015;3369.629340772513;3503.948815355383;3839.2705385976574;4114.670826333946;6704.932950670431;6852.972054191911;7941.563214023168;8030.437869349206;8043.436959412785;7660.387169026464;7958.522488650699;7965.725483922544;7986.480878742979;7994.360451163893;7983.920160798318;7990.960045199737;7993.72021979228;7997.920020799834;8029.197080291808;7535.095788505195;7716.498427602188;7720.845583088195;7723.53658780475;7790.259925307087;6950.478714096362;7605.885911711218;7636.08366995964;7674.386049116169;7710.383169346326;6610.438112760276;8146.429749917449;8154.1030486647605;8176.305014073871;8189.303909167787;4862.440819305825;4886.340344053508;4889.657723959289;4896;4927.802887884444;7549.433792465477;7808.57052862098;7828.647710853139;7858.842823143619;7862.253085956825;7471.3649339806725;7517.060367454068;7542.170361260367;7556.65995030236;7561.621918903923;7536.623168841646;7542.094948606203;7546.962265188639;7557;7567.962160189164;7330.193678695233;7343.3390994810725;7346.926530734838;7350.52221605594;7374.151972523228;7198.028266183998;7527.849443011219;7569.243075692387;7580.241975802375;7583.886241706489;5608.775648973995;7213.9278607213255;7354.043974283309;7358.1538123116525;7398.778036658909 04:00:27 INFO - PROCESS | 1660 | |5;yui.html;701.6456689371863;712.9786106416807;730.813642521157;736.7200463823746;765.5789315876267;15528.223588820556;15813.367465301402;15877.206139693013;15886.682266354666;15925.805564582659;14649.121052736817;14685;14701.92649036756;14726.926365368185;14731.263436828169;772.8338407242455;953.8521529162991;959.4770849886828;985.8767654043245;991.177322822056;966.7293157915759;980.7744218829673;981.1562056631292;981.3719219699398;985;1024.3137098144225;1029.1458089785497;1033.018632299315;1033.6278939581744;1035.3684252605904;1023.5445226874044;1026.1175389165312;1029.8197815382277;1030.5208078243616;1035.3270374256717;764.5718397697308;769.93840492761;769.9576523291216;771.4522688890838;772.7025095338279;15214.771778423332;15248.23758812066;15533.766993495106;16046.438374656831;16068.839311606967;13341.466341346335;13380.19718816874;13409.458917438726;13429.932850335783;13514.526991555347;10352.482375881176;10361.585536578528;10416.947915260374;10437.321574097736;10545.683629491128;3605.7115430765475;3611.115276757211;3622.800745958971;3656.6708996190205;3681.932239650498;82415.5879220606;84007.5799620998;84954.15045849586;84978;85348.71976920351;8294.626741796634;9459.76350591227;9601.183899368492;9873.40759554429;10036.548355324028;23142.190023349438;23305.300840974796;23704.40738981539;23810.404739881295;24414.877925610257;6550.8362290943105;6750.628715420699;7780.572068536285;7787.143414224431;8056.154103819132;4135.255653982312;4198.5591512891315;4215.220184265921;4286.292761694348;4310.870673879788;3766.37854753968;3797.9810100949317;3810.3522401191863;3817.1411432428035;3829.329867273216;718.005562296213;728.5847067171661;732.6666366803091;737.9741709040158;743.84007438401;545.6835035679296;572.762303643983;610.2158726037021;623.2427600465456;623.8970569856014;8097.554634495042;8571;8615.61229744663;8635.654573816977;8645.691380764843;550.5870597052211;559.7397210297231;602.3494625804121;604.5465900574569;646.1761254400583;3921.411788231731;4039.1921615676983;4077.347624380144;4088.264112459786;4264.04059086703;75757;93588;94612;94650.52674736582;95186;79253;79326.41347173024;79376;80404;85383.71924421139;73632.26367736254;78359;78945.60527197327;79631.60184199041;82915.5854220725;8191.9350484436645;8655.783605409915;8819;8995.73901729226;9078.409903356262;47125.528744712115;50643;52604.68488287823;54139;55071;68240.63518729697;70749.29250707426;72015;74171.62914185395;74854.62572687102;11316.547338106384;11728.648140555797;11838.881611184122;11996.280223186637;12045.457954392248;12032;12307.815382769444;12321.075919306268;12612.87387126117;12622.55821046259;10439.373637581466;11625.24435911663;11833.290002599528;11871.643850684493;12060.698482538006;11579.418344519016;11638.243514171552;11822.29066255993;11899.76200476003;11949.22330048544;11750.471228794553;11779.175457717878;11798.343270720625;11997.100217483548;12039.578614748441;9985.800283994426;10065.798684026426;10076.496175191358;10081.495925203564;10308.896911030793;8287.917120828955;10076.294659373774;10081.949590252001;10107.949460252652;10153.695389138338 04:00:27 INFO - PROCESS | 1660 | __end_tp_report 04:00:27 INFO - PROCESS | 1660 | __start_cc_report 04:00:27 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,367 04:00:27 INFO - PROCESS | 1660 | __end_cc_report 04:00:27 INFO - PROCESS | 1660 | __startTimestamp1461754827121__endTimestamp 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:00:27 INFO - PROCESS | 1660 | Number of tests: 6 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5702.56 Median:5999.31 stddev:648.95 (10.8%) stddev-sans-first:626.28 04:00:27 INFO - PROCESS | 1660 | Values: 3443.6 3499.6 3510.4 3553.2 3553.9 5716.5 5734.5 5743.2 5759.7 5799.4 5020.9 5745.9 5763.7 5800.2 5809.1 5135.6 5144.5 5156.6 5193.7 5220.6 5145.8 5224.7 5241.0 5248.3 5258.2 5209.7 5222.7 5241.5 5251.0 5324.5 5059.3 5214.3 5283.6 5292.8 5321.2 5106.8 5174.9 5211.3 5244.2 5246.3 4461.2 5516.3 5621.6 5661.9 5679.8 6112.5 6132.0 6170.2 6184.0 6191.2 5944.3 6197.3 6208.7 6209.0 6228.8 4828.4 5131.9 5888.4 5907.9 5925.1 6242.1 6264.9 6277.7 6293.2 6295.4 6020.7 6226.5 6228.4 6240.2 6250.2 5251.3 6159.6 6199.7 6248.1 6274.1 6187.9 6205.4 6213.0 6221.4 6233.0 5435.2 6119.2 6159.5 6233.5 6241.1 4875.6 4913.5 4917.2 4930.0 4948.3 4383.0 4411.7 4418.7 4424.3 4441.9 4377.6 4695.6 5734.0 5884.0 5912.3 6134.4 6137.3 6192.6 6226.7 6230.8 5336.7 5775.3 5779.5 5790.2 5790.5 5311.1 5397.4 5429.8 5436.1 5463.3 5347.6 6275.3 6284.6 6290.9 6313.3 6192.4 6230.1 6249.8 6258.4 6297.7 6185.8 6279.8 6282.7 6307.4 6327.5 6182.3 6197.2 6226.1 6236.0 6267.4 5225.5 6035.3 6196.9 6212.8 6256.0 5999.3 6016.2 6054.2 6124.8 6152.2 6147.7 6170.4 6189.4 6210.7 6212.1 5977.9 6013.5 6070.4 6086.9 6099.7 5966.2 6125.3 6162.4 6164.5 6190.0 6008.3 6037.2 6038.3 6040.4 6057.8 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:65851.99 Median:38090.92 stddev:145205.82 (381.2%) stddev-sans-first:145530.20 04:00:27 INFO - PROCESS | 1660 | Values: 475.9 526.3 602.9 630.4 674.9 37638.8 38543.0 38566.8 38649.6 38702.4 33939.0 40089.6 40179.0 40273.6 40357.0 9450.7 9669.5 9672.7 9676.7 9690.4 9480.6 9759.9 9776.9 9795.6 9803.0 9732.8 9814.2 9824.1 9830.1 9837.6 9709.3 9797.9 9800.7 9821.4 9858.2 9633.6 9641.4 9708.5 9753.0 9770.9 33201.3 39084.4 39186.0 39198.2 39490.6 3299.6 3351.5 3369.9 3391.7 3960.0 28002.6 28350.7 28436.4 28536.0 28550.9 2788.4 2801.1 2806.4 2811.1 2860.7 390789.0 400842.0 416678.0 416862.0 421174.0 48004.3 48021.8 52774.5 64041.7 64374.0 46921.5 47232.8 47316.8 47946.0 47948.0 16904.0 16993.9 16997.2 17104.7 17127.2 13626.5 13677.1 13721.7 13819.2 13881.2 15373.6 15403.0 15421.3 15433.8 15493.8 5619.8 6181.3 6221.7 6680.5 6717.2 908.6 1007.2 1084.7 1101.0 1138.1 27102.7 30290.2 31245.4 31777.0 31847.4 1081.8 1094.7 1110.4 1113.0 1208.6 14879.8 15113.3 15148.4 15232.0 15315.6 666172.0 680067.0 687310.0 688945.0 690029.0 57426.4 59277.4 59464.0 59650.4 59667.4 1458.2 1500.8 1538.4 1549.0 1551.5 25729.7 27361.7 29995.6 30350.2 30703.4 50776.2 51327.7 51474.7 51481.2 51497.7 390298.0 510864.0 517442.0 519625.0 541929.0 33441.8 33697.2 33911.3 34138.3 34285.0 43147.0 43883.0 44054.0 44195.0 45551.1 36377.5 36389.9 36509.5 36775.4 36932.4 35914.8 36156.6 36372.6 36469.3 36493.6 31519.9 35608.6 36568.8 36688.0 36788.4 24276.9 24523.0 31134.2 31476.5 31535.8 24399.5 24407.6 24534.9 24932.3 25043.6 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34890.50 Median:24578.88 stddev:101296.84 (412.1%) stddev-sans-first:101547.84 04:00:27 INFO - PROCESS | 1660 | Values: 369.3 372.2 376.0 376.1 379.1 20115.3 20685.7 20699.9 21682.9 21754.0 21094.1 21191.7 21434.7 21469.5 21494.6 5785.4 5986.1 5997.0 6023.2 6024.2 4100.7 5133.2 5620.1 6023.3 6033.5 5964.6 5983.9 6024.7 6033.6 6057.5 5844.8 6538.2 6552.2 6559.0 6559.3 5735.8 5790.7 5799.9 5802.9 5831.8 11911.9 13810.7 24237.0 24920.8 25239.7 2276.0 2283.7 2752.6 2838.9 2867.6 9742.2 10094.8 10278.9 10315.0 10372.4 1698.4 1704.5 1752.7 1760.3 1772.9 107619.0 116092.4 122030.8 122216.0 123431.0 10036.9 11441.7 11676.6 11736.3 11740.0 14357.5 14372.2 14374.0 14427.1 14462.7 9044.1 9054.8 9071.8 9083.0 9098.5 9121.0 9233.3 9247.3 9283.5 9334.2 4521.3 4603.4 4611.7 4612.4 4618.0 1705.6 1819.4 1869.9 1928.7 1948.5 416.1 434.2 466.4 1024.6 1056.5 11487.5 11794.0 11922.4 12166.3 12262.2 441.7 468.4 472.4 498.3 882.6 8803.3 10822.3 11125.6 11213.6 11862.3 560592.0 562168.0 568839.0 660297.0 674473.0 117920.4 118829.4 121563.0 124115.0 132199.0 991.0 1039.8 1056.9 1087.0 1091.6 17508.7 17837.4 18005.7 18148.5 18476.0 53367.5 54209.2 54793.5 56459.4 56863.4 66215.7 80762.6 83504.0 84533.0 85449.0 6578.2 7336.9 7929.2 8248.0 8472.0 9457.3 11564.4 12651.7 13602.9 13615.2 11466.3 11738.6 11879.9 11892.0 11945.6 11334.3 11497.8 11703.9 11807.6 11842.5 11701.3 11877.6 11923.8 11989.5 12084.3 8248.7 8263.7 8302.8 8309.3 8493.2 7639.9 9752.7 11090.2 12129.2 12146.4 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:6409.35 Median:5059.44 stddev:3401.70 (67.2%) stddev-sans-first:3391.89 04:00:27 INFO - PROCESS | 1660 | Values: 1582.4 1685.5 2032.8 2068.4 2125.1 8371.1 8667.9 9249.4 9431.5 9482.5 7932.6 9254.2 9354.7 9370.4 9411.2 2542.1 2578.6 2580.8 2583.6 2592.0 2118.7 2538.7 2556.6 2563.9 2599.4 2229.3 2539.2 2609.6 2615.1 2644.6 2580.8 2592.6 2607.7 2615.8 2630.4 2586.3 2590.5 2599.7 2604.8 2624.7 9397.1 9402.0 9426.6 9473.0 9493.8 5120.5 5304.8 5825.5 5853.9 5919.1 3254.6 3652.3 3741.3 3768.7 3789.5 1048.6 1070.4 1070.5 1079.7 1085.3 12576.9 12602.4 12773.3 12779.2 12779.6 5107.5 5115.7 5180.0 5189.8 5230.8 10751.3 10756.2 10868.8 10871.7 10890.7 5706.8 5819.9 5830.4 5844.2 5851.5 4643.9 5019.3 5099.5 5147.8 5244.2 4145.1 4147.3 4169.1 4176.3 4182.1 1360.1 1374.6 1389.2 1392.8 1395.1 1379.5 1407.0 1416.0 1425.7 1444.0 8624.9 8850.3 8907.5 8915.2 8921.8 1157.8 1210.6 1267.0 1277.6 1278.2 6504.1 6557.8 6575.6 6649.4 6736.7 11121.8 11548.1 11560.5 11563.8 11569.4 11295.2 11437.6 11570.4 11583.2 11605.3 8799.4 8937.4 9002.6 9029.7 9111.1 8267.5 8307.5 8385.8 8389.8 8465.5 9985.9 10000.1 10080.4 10183.9 10228.8 10533.0 10565.9 10810.7 10839.2 10856.0 6780.0 7210.5 7365.6 7442.1 7568.2 8662.7 8725.1 8734.7 8764.0 8809.3 7631.8 7690.1 7766.7 7773.5 7891.7 7133.9 7245.8 7547.1 7582.8 7730.8 7549.8 8193.6 8212.2 8239.9 8241.2 6977.6 7251.7 7384.5 7434.9 7465.6 6656.8 7177.9 7398.6 7580.1 7583.9 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:6089.32 Median:6880.42 stddev:1953.24 (28.4%) stddev-sans-first:1909.57 04:00:27 INFO - PROCESS | 1660 | Values: 288.7 341.1 346.1 347.1 347.8 7041.2 7077.6 7078.0 7107.0 7113.5 7012.2 7023.8 7037.3 7082.5 7089.2 4906.3 4994.1 5108.5 5137.5 5220.4 5076.2 5103.5 5104.6 5107.6 5110.4 5019.9 5043.5 5082.7 5086.9 5101.6 4345.7 5068.7 5110.7 5121.6 5141.1 4412.2 4418.1 4420.4 4426.8 4442.7 6560.8 6728.6 6740.0 6771.6 6839.4 5971.4 6028.2 6038.6 6042.5 6044.5 6426.7 7413.1 7413.6 7423.8 7437.3 4928.7 4943.0 4948.3 4971.3 4972.3 7079.9 7850.5 7852.4 7864.1 7886.9 4951.9 4985.0 4989.7 5008.8 5015.7 5106.1 5108.1 5114.2 5124.3 5146.5 5946.4 5954.2 5975.5 5991.2 6012.2 7434.1 7463.2 7474.5 7483.2 7496.5 1696.1 1713.4 1767.0 1773.4 1804.4 1141.9 1152.6 1167.3 1168.1 1168.7 6872.2 6873.1 6875.0 6889.3 6919.0 7713.7 7717.0 7725.7 7729.2 7782.1 6242.8 6846.2 6876.1 6884.7 6905.6 3060.4 3369.6 3503.9 3839.3 4114.7 6704.9 6853.0 7941.6 8030.4 8043.4 7660.4 7958.5 7965.7 7986.5 7994.4 7983.9 7991.0 7993.7 7997.9 8029.2 7535.1 7716.5 7720.8 7723.5 7790.3 6950.5 7605.9 7636.1 7674.4 7710.4 6610.4 8146.4 8154.1 8176.3 8189.3 4862.4 4886.3 4889.7 4896.0 4927.8 7549.4 7808.6 7828.6 7858.8 7862.3 7471.4 7517.1 7542.2 7556.7 7561.6 7536.6 7542.1 7547.0 7557.0 7568.0 7330.2 7343.3 7346.9 7350.5 7374.2 7198.0 7527.8 7569.2 7580.2 7583.9 5608.8 7213.9 7354.0 7358.2 7398.8 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:19127.03 Median:4239.63 stddev:26785.47 (631.8%) stddev-sans-first:26824.88 04:00:27 INFO - PROCESS | 1660 | Values: 701.6 713.0 730.8 736.7 765.6 15528.2 15813.4 15877.2 15886.7 15925.8 14649.1 14685.0 14701.9 14726.9 14731.3 772.8 953.9 959.5 985.9 991.2 966.7 980.8 981.2 981.4 985.0 1024.3 1029.1 1033.0 1033.6 1035.4 1023.5 1026.1 1029.8 1030.5 1035.3 764.6 769.9 770.0 771.5 772.7 15214.8 15248.2 15533.8 16046.4 16068.8 13341.5 13380.2 13409.5 13429.9 13514.5 10352.5 10361.6 10416.9 10437.3 10545.7 3605.7 3611.1 3622.8 3656.7 3681.9 82415.6 84007.6 84954.2 84978.0 85348.7 8294.6 9459.8 9601.2 9873.4 10036.5 23142.2 23305.3 23704.4 23810.4 24414.9 6550.8 6750.6 7780.6 7787.1 8056.2 4135.3 4198.6 4215.2 4286.3 4310.9 3766.4 3798.0 3810.4 3817.1 3829.3 718.0 728.6 732.7 738.0 743.8 545.7 572.8 610.2 623.2 623.9 8097.6 8571.0 8615.6 8635.7 8645.7 550.6 559.7 602.3 604.5 646.2 3921.4 4039.2 4077.3 4088.3 4264.0 75757.0 93588.0 94612.0 94650.5 95186.0 79253.0 79326.4 79376.0 80404.0 85383.7 73632.3 78359.0 78945.6 79631.6 82915.6 8191.9 8655.8 8819.0 8995.7 9078.4 47125.5 50643.0 52604.7 54139.0 55071.0 68240.6 70749.3 72015.0 74171.6 74854.6 11316.5 11728.6 11838.9 11996.3 12045.5 12032.0 12307.8 12321.1 12612.9 12622.6 10439.4 11625.2 11833.3 11871.6 12060.7 11579.4 11638.2 11822.3 11899.8 11949.2 11750.5 11779.2 11798.3 11997.1 12039.6 9985.8 10065.8 10076.5 10081.5 10308.9 8287.9 10076.3 10081.9 10107.9 10153.7 04:00:27 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:00:27 INFO - PROCESS | 1660 | 04:00:27 INFO - TEST-INFO | 1660: exit 0 04:00:27 INFO - TEST-OK | dromaeo_css | took 1110483ms 04:00:27 INFO - TEST-START | kraken 04:00:27 INFO - operating with platform_type : mac_ 04:00:27 INFO - Initialising browser for kraken test... 04:00:27 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpjiam3q/profile http://localhost:49277/getInfo.html) 04:00:30 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 04:00:30 INFO - PROCESS | 1675 | colorDepth:24 04:00:30 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 04:00:30 INFO - PROCESS | 1675 | __metrics 04:00:30 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2849: TypeError: this.tabs is undefined 04:00:31 INFO - TEST-INFO | 1675: exit 0 04:00:31 INFO - Browser initialized. 04:00:31 INFO - Running cycle 1/1 for kraken test... 04:00:31 INFO - TEST-INFO | started process 1678 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/T/tmpjiam3q/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:00:43 INFO - PROCESS | 1678 | RSS: Main: 217853952 04:00:43 INFO - PROCESS | 1678 | 04:00:45 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 04:00:46 INFO - PROCESS | 1678 | RSS: Main: 229949440 04:00:46 INFO - PROCESS | 1678 | 04:00:49 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 04:00:49 INFO - PROCESS | 1678 | RSS: Main: 238776320 04:00:49 INFO - PROCESS | 1678 | 04:00:52 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 04:00:53 INFO - PROCESS | 1678 | RSS: Main: 242429952 04:00:53 INFO - PROCESS | 1678 | 04:00:55 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 04:00:56 INFO - PROCESS | 1678 | RSS: Main: 243920896 04:00:56 INFO - PROCESS | 1678 | 04:00:58 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:00:58 INFO - PROCESS | 1678 | RSS: Main: 244445184 04:00:58 INFO - PROCESS | 1678 | 04:01:01 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 04:01:02 INFO - PROCESS | 1678 | RSS: Main: 244445184 04:01:02 INFO - PROCESS | 1678 | 04:01:04 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 04:01:05 INFO - PROCESS | 1678 | RSS: Main: 244473856 04:01:05 INFO - PROCESS | 1678 | 04:01:08 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 04:01:08 INFO - PROCESS | 1678 | RSS: Main: 244420608 04:01:08 INFO - PROCESS | 1678 | 04:01:10 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:01:11 INFO - PROCESS | 1678 | RSS: Main: 244363264 04:01:11 INFO - PROCESS | 1678 | 04:01:12 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:01:13 INFO - PROCESS | 1678 | RSS: Main: 244436992 04:01:13 INFO - PROCESS | 1678 | 04:01:15 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:01:16 INFO - PROCESS | 1678 | RSS: Main: 244666368 04:01:16 INFO - PROCESS | 1678 | 04:01:18 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:01:19 INFO - PROCESS | 1678 | RSS: Main: 244748288 04:01:19 INFO - PROCESS | 1678 | 04:01:21 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:01:22 INFO - PROCESS | 1678 | RSS: Main: 244809728 04:01:22 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 04:01:24 INFO - PROCESS | 1678 | RSS: Main: 246677504 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | __start_tp_report 04:01:24 INFO - PROCESS | 1678 | _x_x_mozilla_page_load 04:01:24 INFO - PROCESS | 1678 | _x_x_mozilla_page_load_details 04:01:24 INFO - PROCESS | 1678 | |i|pagename|runs| 04:01:24 INFO - PROCESS | 1678 | |0;ai-astar;124;109;115;116;116;128;114;116;118;115 04:01:24 INFO - PROCESS | 1678 | |1;audio-beat-detection;149;117;139;152;115;158;131;152;185;148 04:01:24 INFO - PROCESS | 1678 | |2;audio-dft;193;195;224;226;193;219;272;205;198;199 04:01:24 INFO - PROCESS | 1678 | |3;audio-fft;71;68;74;110;58;56;59;58;123;92 04:01:24 INFO - PROCESS | 1678 | |4;audio-oscillator;85;80;121;77;84;82;115;79;78;122 04:01:24 INFO - PROCESS | 1678 | |5;imaging-gaussian-blur;89;88;92;91;89;86;91;87;90;107 04:01:24 INFO - PROCESS | 1678 | |6;imaging-darkroom;93;95;94;94;96;94;98;96;95;95 04:01:24 INFO - PROCESS | 1678 | |7;imaging-desaturate;90;136;142;117;93;110;147;98;90;94 04:01:24 INFO - PROCESS | 1678 | |8;json-parse-financial;53;58;59;59;60;60;58;66;56;54 04:01:24 INFO - PROCESS | 1678 | |9;json-stringify-tinderbox;63;63;74;76;73;70;64;66;69;65 04:01:24 INFO - PROCESS | 1678 | |10;stanford-crypto-aes;84;88;86;83;82;84;77;83;84;85 04:01:24 INFO - PROCESS | 1678 | |11;stanford-crypto-ccm;150;132;134;120;119;120;119;118;123;127 04:01:24 INFO - PROCESS | 1678 | |12;stanford-crypto-pbkdf2;136;137;136;163;172;168;137;136;137;123 04:01:24 INFO - PROCESS | 1678 | |13;stanford-crypto-sha256-iterative;68;66;65;79;66;85;90;81;67;64 04:01:24 INFO - PROCESS | 1678 | __end_tp_report 04:01:24 INFO - PROCESS | 1678 | __start_cc_report 04:01:24 INFO - PROCESS | 1678 | _x_x_mozilla_cycle_collect,923 04:01:24 INFO - PROCESS | 1678 | __end_cc_report 04:01:24 INFO - PROCESS | 1678 | __startTimestamp1461754884460__endTimestamp 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | ------- Summary: start ------- 04:01:24 INFO - PROCESS | 1678 | Number of tests: 14 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#0] ai-astar Cycles:10 Average:117.10 Median:116.00 stddev:5.32 (4.6%) stddev-sans-first:5.02 04:01:24 INFO - PROCESS | 1678 | Values: 124.0 109.0 115.0 116.0 116.0 128.0 114.0 116.0 118.0 115.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#1] audio-beat-detection Cycles:10 Average:144.60 Median:150.50 stddev:20.57 (13.7%) stddev-sans-first:21.75 04:01:24 INFO - PROCESS | 1678 | Values: 149.0 117.0 139.0 152.0 115.0 158.0 131.0 152.0 185.0 148.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#2] audio-dft Cycles:10 Average:212.40 Median:212.00 stddev:24.52 (11.6%) stddev-sans-first:24.99 04:01:24 INFO - PROCESS | 1678 | Values: 193.0 195.0 224.0 226.0 193.0 219.0 272.0 205.0 198.0 199.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#3] audio-fft Cycles:10 Average:76.90 Median:63.50 stddev:23.67 (37.3%) stddev-sans-first:25.01 04:01:24 INFO - PROCESS | 1678 | Values: 71.0 68.0 74.0 110.0 58.0 56.0 59.0 58.0 123.0 92.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#4] audio-oscillator Cycles:10 Average:92.30 Median:79.50 stddev:18.90 (23.8%) stddev-sans-first:19.86 04:01:24 INFO - PROCESS | 1678 | Values: 85.0 80.0 121.0 77.0 84.0 82.0 115.0 79.0 78.0 122.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#5] imaging-gaussian-blur Cycles:10 Average:91.00 Median:89.50 stddev:5.93 (6.6%) stddev-sans-first:6.24 04:01:24 INFO - PROCESS | 1678 | Values: 89.0 88.0 92.0 91.0 89.0 86.0 91.0 87.0 90.0 107.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#6] imaging-darkroom Cycles:10 Average:95.00 Median:95.00 stddev:1.41 (1.5%) stddev-sans-first:1.30 04:01:24 INFO - PROCESS | 1678 | Values: 93.0 95.0 94.0 94.0 96.0 94.0 98.0 96.0 95.0 95.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#7] imaging-desaturate Cycles:10 Average:111.70 Median:90.00 stddev:22.55 (25.1%) stddev-sans-first:22.51 04:01:24 INFO - PROCESS | 1678 | Values: 90.0 136.0 142.0 117.0 93.0 110.0 147.0 98.0 90.0 94.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#8] json-parse-financial Cycles:10 Average:58.30 Median:59.00 stddev:3.62 (6.1%) stddev-sans-first:3.30 04:01:24 INFO - PROCESS | 1678 | Values: 53.0 58.0 59.0 59.0 60.0 60.0 58.0 66.0 56.0 54.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#9] json-stringify-tinderbox Cycles:10 Average:68.30 Median:69.50 stddev:4.81 (6.9%) stddev-sans-first:4.70 04:01:24 INFO - PROCESS | 1678 | Values: 63.0 63.0 74.0 76.0 73.0 70.0 64.0 66.0 69.0 65.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:2.88 (3.4%) stddev-sans-first:3.05 04:01:24 INFO - PROCESS | 1678 | Values: 84.0 88.0 86.0 83.0 82.0 84.0 77.0 83.0 84.0 85.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#11] stanford-crypto-ccm Cycles:10 Average:126.20 Median:125.00 stddev:10.11 (8.1%) stddev-sans-first:6.02 04:01:24 INFO - PROCESS | 1678 | Values: 150.0 132.0 134.0 120.0 119.0 120.0 119.0 118.0 123.0 127.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:144.50 Median:137.00 stddev:16.66 (12.2%) stddev-sans-first:17.39 04:01:24 INFO - PROCESS | 1678 | Values: 136.0 137.0 136.0 163.0 172.0 168.0 137.0 136.0 137.0 123.0 04:01:24 INFO - PROCESS | 1678 | 04:01:24 INFO - PROCESS | 1678 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:73.50 stddev:9.64 (13.1%) stddev-sans-first:10.05 04:01:24 INFO - PROCESS | 1678 | Values: 68.0 66.0 65.0 79.0 66.0 85.0 90.0 81.0 67.0 64.0 04:01:24 INFO - PROCESS | 1678 | -------- Summary: end -------- 04:01:24 INFO - PROCESS | 1678 | 04:01:25 INFO - TEST-INFO | 1678: exit 0 04:01:25 INFO - TEST-OK | kraken | took 57673ms 04:01:25 INFO - SUITE-END | took 1168s 04:01:25 INFO - Completed test suite (00:19:28) 04:01:25 INFO - javascript benchmark 04:01:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"lowerIsBetter": false, "replicates": [3443.6039855416625, 3499.615042345343, 3510.3505851417503, 3553.200529880777, 3553.857845686173, 5716.456936591027, 5734.541236701064, 5743.167240750094, 5759.712014399284, 5799.391063938289, 5020.9497905020935, 5745.856353591163, 5763.682997435149, 5800.245968024151, 5809.099589563624, 5135.5634771044415, 5144.511271429208, 5156.561692256163, 5193.662411943231, 5220.582353411718, 5145.81989630363, 5224.712640804754, 5240.973795131038, 5248.265242866002, 5258.237555554461, 5209.687418754868, 5222.712750798704, 5241.528262456378, 5250.973745131269, 5324.467553244664, 5059.266406371074, 5214.270002199695, 5283.63014588979, 5292.788288468457, 5321.228421878806, 5106.795728170869, 5174.948250517485, 5211.296474975868, 5244.213367994793, 5246.291750613656, 4461.152381047623, 5516.3380394352935, 5621.634593751393, 5661.886762264773, 5679.772809087632, 6112.541559383019, 6131.969340153315, 6170.167027451282, 6184.0, 6191.195144631214, 5944.256967879015, 6197.318294987548, 6208.689565521706, 6208.96895515524, 6228.750849966042, 4828.444728856191, 5131.871703207413, 5888.352281249016, 5907.940920590825, 5925.140854576062, 6242.063690446378, 6264.874702505925, 6277.7175027123885, 6293.244810622755, 6295.37046295376, 6020.668863212565, 6226.470749986212, 6228.408301211378, 6240.219972503437, 6250.218722659668, 5251.343582052244, 6159.630422174684, 6199.659018753921, 6248.094026366151, 6274.058891166362, 6187.876242475125, 6205.410486003822, 6212.968935155295, 6221.377862213742, 6232.968835155886, 5435.219308470309, 6119.173911521888, 6159.476444502178, 6233.470155036782, 6241.095041218998, 4875.561199492063, 4913.48408417111, 4917.164082106051, 4930.0, 4948.307236986825, 4383.013821890049, 4411.713238639478, 4418.734875907456, 4424.269995450714, 4441.888952776142, 4377.584129507692, 4695.5539223773685, 5733.971330143323, 5884.029135192731, 5912.320083190488, 6134.436587033474, 6137.254412462224, 6192.628442293477, 6226.6575338355915, 6230.7819226326865, 5336.706481143575, 5775.27809023872, 5779.479846813807, 5790.247267855152, 5790.507806836382, 5311.097113490716, 5397.379301380313, 5429.809956651498, 5436.130219164915, 5463.262459567907, 5347.572194224531, 6275.34108918557, 6284.591501552384, 6290.874182516324, 6313.337099604475, 6192.411720886508, 6230.127782110599, 6249.78125765596, 6258.405451482102, 6297.685115744102, 6185.8144255672405, 6279.843003924939, 6282.685865706605, 6307.400796924285, 6327.525435592257, 6182.28903676083, 6197.225346831646, 6226.128342032029, 6236.0, 6267.4045965633195, 5225.451327610698, 6035.336113027475, 6196.938030619636, 6212.81361559154, 6255.96872015637, 5999.310079340931, 6016.157737916607, 6054.152418661479, 6124.846878828065, 6152.169457123231, 6147.692615369303, 6170.413810687977, 6189.381061893775, 6210.689465526615, 6212.1303017578475, 5977.850553736192, 6013.488853447506, 6070.362611925861, 6086.908696369469, 6099.695015249308, 5966.224390829165, 6125.264968203844, 6162.383761623802, 6164.506839452923, 6190.0405437157315, 6008.279006519246, 6037.245344331958, 6038.275406951194, 6040.395960403925, 6057.848553786191], "unit": "score", "name": "dojo.html", "value": 5664.855386993502}, {"lowerIsBetter": false, "replicates": [475.864378652084, 526.2842534153552, 602.9216201893754, 630.4168644004295, 674.9021391898174, 37638.81180594097, 38543.03642408941, 38566.84299471013, 38649.61350386495, 38702.41946370803, 33938.981830545126, 40089.59910400895, 40179.0, 40273.597264027354, 40357.0, 9450.724152239429, 9669.468179250138, 9672.661456849011, 9676.661316853912, 9690.370125941827, 9480.620775168985, 9759.902400976005, 9776.90223097767, 9795.559199836025, 9802.950985245065, 9732.75668108296, 9814.214862810994, 9824.115829575338, 9830.115289623933, 9837.606495740198, 9709.320347575673, 9797.902020979805, 9800.656977005841, 9821.410715357066, 9858.16205622523, 9633.566489507919, 9641.42151470914, 9708.466034368106, 9752.97593752653, 9770.90229097707, 33201.3359732804, 39084.41373379402, 39186.0, 39198.21603567913, 39490.60509394898, 3299.637039925606, 3351.4805205193234, 3369.898903032913, 3391.6777906098882, 3960.0, 28002.569043031282, 28350.716492835014, 28436.431271374662, 28536.0, 28550.857245713847, 2788.442311537701, 2801.1176479408923, 2806.3545384561667, 2811.12855014944, 2860.67102283238, 390789.0, 400842.0, 416678.0, 416862.0, 421174.0, 48004.279935800994, 48021.75989120032, 52774.47225527695, 64041.71916561736, 64374.0, 46921.5307846924, 47232.7638361806, 47316.7634161827, 47946.04107917892, 47948.0, 16904.0, 16993.91503042477, 16997.150142492825, 17104.657906841792, 17127.229274682668, 13626.454941802414, 13677.110987785762, 13721.725565488634, 13819.170849749047, 13881.236531990837, 15373.615659608598, 15403.0, 15421.306041228172, 15433.768493472608, 15493.767593486107, 5619.831405057855, 6181.260337495751, 6221.688915554204, 6680.465562754968, 6717.193936727526, 908.6047569307373, 1007.219404961161, 1084.7017070305571, 1100.9540936110727, 1138.0610995928228, 27102.72897271002, 30290.242743931136, 31245.375092498478, 31777.0, 31847.363052738816, 1081.8160912644869, 1094.7208461842338, 1110.4003837927428, 1112.955481780736, 1208.601161616682, 14879.776803347959, 15113.3199006045, 15148.448211392248, 15231.96326520413, 15315.617109572127, 666172.0, 680067.0, 687310.0, 688945.0, 690029.0, 57426.42573574211, 59277.40722592805, 59464.0, 59650.403495964485, 59667.40332596619, 1458.1761304862507, 1500.8274048484345, 1538.438469958457, 1548.9767653485208, 1551.4957638767446, 25729.742702572734, 27361.726382735917, 29995.550066749453, 30350.241243969078, 30703.385932281675, 50776.23835642542, 51327.743361282955, 51474.74262628663, 51481.22778158256, 51497.7425112872, 390298.0, 510864.0, 517442.0, 519625.0, 541929.0, 33441.83279083687, 33697.157571059935, 33911.32177356489, 34138.31723365568, 34285.0, 43147.0, 43883.0, 44054.0, 44195.0, 45551.08897821959, 36377.454338185475, 36389.90830275096, 36509.45235821412, 36775.44836827396, 36932.44601331036, 35914.820425897706, 36156.63843361533, 36372.63627363693, 36469.27061458703, 36493.635063649024, 31519.896803611762, 35608.64391356053, 36568.817155914054, 36688.0, 36788.44817327688, 24276.907539160427, 24523.0, 31134.22164445907, 31476.527852082694, 31535.842320788248, 24399.51200976006, 24407.633885492087, 24534.87732561326, 24932.252032439057, 25043.624345634467], "unit": "score", "name": "ext.html", "value": 19420.448463363722}, {"lowerIsBetter": false, "replicates": [369.30754834684967, 372.1552076785696, 375.9699224062075, 376.05234808283126, 379.0920744816167, 20115.295964641242, 20685.68971465429, 20699.896500517494, 21682.89158554205, 21753.99999999998, 21094.050767715453, 21191.68212476814, 21434.678479822815, 21469.463263418424, 21494.570108597858, 5785.3925337839555, 5986.102084687288, 5996.97001514994, 6023.156758053876, 6024.186734790791, 4100.7334523255895, 5133.1786914093755, 5620.1288800235825, 6023.337432882379, 6033.456988871001, 5964.552658550604, 5983.910241346383, 6024.668643224621, 6033.607815491979, 6057.51539876811, 5844.824655260349, 6538.215414150309, 6552.246491653425, 6559.048937904025, 6559.27847936729, 5735.827925162252, 5790.652560846363, 5799.927013502515, 5802.883942321172, 5831.825045248649, 11911.880881191164, 13810.65473363164, 24237.0, 24920.750792492025, 25239.747602523923, 2275.977240227593, 2283.7259528856475, 2752.587111933206, 2838.914832555027, 2867.641544806899, 9742.220622350194, 10094.848577271347, 10278.948605257001, 10315.0, 10372.377657340583, 1698.388580111159, 1704.4886534039738, 1752.71080271755, 1760.3310741918117, 1772.8670349723823, 107619.0, 116092.41953790262, 122030.77969220372, 122216.0, 123431.0, 10036.949815251022, 11441.741408445065, 11676.649700508999, 11736.295822250693, 11740.002099821611, 14357.497487588093, 14372.209528476034, 14373.993820432679, 14427.134371937716, 14462.710745785025, 9044.14080662336, 9054.81890362189, 9071.773205669911, 9083.000869904305, 9098.454092754324, 9121.0, 9233.261339092856, 9247.306452016126, 9283.535823208813, 9334.159925606727, 4521.299198624219, 4603.401557797465, 4611.723296602215, 4612.400387949545, 4617.960958784247, 1705.6332888429047, 1819.4450692538637, 1869.906504674788, 1928.6817675083735, 1948.5448335097597, 416.13444036404206, 434.1512343368707, 466.4099913609287, 1024.6413755185624, 1056.529844219315, 11487.483063262172, 11794.0, 11922.403879805976, 12166.330851803235, 12262.20295680778, 441.7415811750098, 468.4168210577852, 472.3858983321668, 498.26505903791616, 882.594006756899, 8803.295736341077, 10822.296550724177, 11125.610603628835, 11213.625980733008, 11862.288262704093, 560592.0, 562168.0, 568839.0, 660297.0, 674473.0, 117920.41039794918, 118829.40585297019, 121563.0, 124115.0, 132199.0, 990.9545429571726, 1039.7608550033535, 1056.941868197241, 1087.0488322717622, 1091.5906535049357, 17508.737368939732, 17837.375691850724, 18005.72991405156, 18148.455546333633, 18476.0, 53367.46632533625, 54209.18686219789, 54793.452065478836, 56459.43540564542, 56863.431365687466, 66215.66892165509, 80762.59618701869, 83504.0, 84533.0, 85449.0, 6578.210614726264, 7336.853262934605, 7929.167437418988, 8247.95876020616, 8472.0, 9457.29070319715, 11564.363959982282, 12651.746965060831, 13602.86397136016, 13615.18308901469, 11466.2546934449, 11738.647840564796, 11879.940600296943, 11892.048636108917, 11945.581904633296, 11334.263272887565, 11497.770044599229, 11703.941480292544, 11807.586734464252, 11842.46708898119, 11701.2979221247, 11877.643670689893, 11923.761524769283, 11989.460474278503, 12084.335361554848, 8248.711295104642, 8263.669453221804, 8302.792430189294, 8309.252167304972, 8493.150684931457, 7639.885401718868, 9752.707418777449, 11090.223684342018, 12129.21160124589, 12146.392680366123], "unit": "score", "name": "jquery.html", "value": 9257.293483006864}, {"lowerIsBetter": false, "replicates": [1582.3828706804347, 1685.469077240669, 2032.8475364347669, 2068.3588087692815, 2125.075592117428, 8371.079181290053, 8667.873176487048, 9249.35254532183, 9431.481268530237, 9482.525873706312, 7932.6033698315, 9254.213391861684, 9354.719358419241, 9370.390924589898, 9411.152996230338, 2542.084849454195, 2578.626099215622, 2580.8064395170345, 2583.599542070982, 2592.0, 2118.724565806443, 2538.7207407185197, 2556.5526032944254, 2563.910338106307, 2599.4021375083744, 2229.342344008521, 2539.212844018351, 2609.6085587161983, 2615.1370047884247, 2644.598362867683, 2580.780633646133, 2592.585186370181, 2607.7131515533274, 2615.8038147138946, 2630.3818602628367, 2586.301698541402, 2590.4689538644534, 2599.6620439342955, 2604.8306860054035, 2624.6981597116387, 9397.107274808883, 9401.96578376378, 9426.622935082589, 9472.952635236848, 9493.762655933588, 5120.513551212629, 5304.840854774363, 5825.504832089277, 5853.9414605854245, 5919.082542205965, 3254.576905002358, 3652.2695460907926, 3741.28915506053, 3768.7361884668085, 3789.450529673182, 1048.567323808764, 1070.379180075562, 1070.4701172919454, 1079.6707004363666, 1085.3325204998869, 12576.874231257754, 12602.432890519949, 12773.29746863922, 12779.23324600527, 12779.61661150167, 5107.540321371094, 5115.7442127893455, 5179.964007198575, 5189.832287735305, 5230.843074707765, 10751.301165424222, 10756.19328550362, 10868.836967445495, 10871.673849784362, 10890.673279801618, 5706.80026199081, 5819.941800582024, 5830.3586605473365, 5844.240248767718, 5851.502622277154, 4643.907121857544, 5019.3474848270225, 5099.5410413063, 5147.794088236428, 5244.239585260116, 4145.129522800185, 4147.336426171858, 4169.124483858423, 4176.31090870003, 4182.142660754538, 1360.0615575253044, 1374.6013656039836, 1389.1526169036879, 1392.8258967629047, 1395.1001603965385, 1379.4689044717857, 1407.0150894373774, 1416.0370947755418, 1425.672095418039, 1444.0469290268582, 8624.913750862537, 8850.291976641853, 8907.51008694515, 8915.153060459246, 8921.821563568692, 1157.8321143434323, 1210.6186551236333, 1266.9873301267053, 1277.5975567696146, 1278.2105052925979, 6504.121943537656, 6557.803265902031, 6575.572587781779, 6649.401553860079, 6736.696848641823, 11121.833172502418, 11548.076153907667, 11560.537578496765, 11563.826542601699, 11569.363684997237, 11295.209335346446, 11437.59968401102, 11570.42147892602, 11583.24708893936, 11605.30368177912, 8799.428037177691, 8937.419067760575, 9002.639894404281, 9029.683961061462, 9111.088891110836, 8267.462614930011, 8307.543085130375, 8385.790355241168, 8389.825424440462, 8465.492070475791, 9985.850212246676, 10000.149987251165, 10080.445575493419, 10183.898161018295, 10228.795424091328, 10533.0, 10565.9471702641, 10810.67567972962, 10839.241253112206, 10856.022957933825, 6779.966100169665, 7210.495265331377, 7365.594892280976, 7442.106947166375, 7568.167501574932, 8662.653493860174, 8725.127487251224, 8734.737957861274, 8764.0, 8809.295256379346, 7631.809204769926, 7690.115636701627, 7766.728164514441, 7773.494722842997, 7891.723789667334, 7133.857322853618, 7245.7826265212125, 7547.05661792276, 7582.772516824504, 7730.7680769577, 7549.811254718676, 8193.631286591999, 8212.219839115274, 8239.876401854097, 8241.175882411711, 6977.616231107338, 7251.746188883364, 7384.4830861839155, 7434.851302974019, 7465.552066876005, 6656.76701315471, 7177.892331614926, 7398.5942670892355, 7580.128285247047, 7583.924160758322], "unit": "score", "name": "mootools.html", "value": 5228.385094588089}, {"lowerIsBetter": false, "replicates": [288.66659008844783, 341.1284168948336, 346.12603176978143, 347.10793261318406, 347.77916023325196, 7041.225465198824, 7077.64611769412, 7077.964610176942, 7107.0, 7113.502054856162, 7012.193597736262, 7023.82440438988, 7037.331453511908, 7082.468814838882, 7089.22018577956, 4906.31311616374, 4994.101061808873, 5108.514691104339, 5137.460566640505, 5220.425753167148, 5076.213186956028, 5103.464136265694, 5104.591632669396, 5107.565856902168, 5110.361204849394, 5019.949800501985, 5043.52086551777, 5082.703910502817, 5086.92369614456, 5101.591872650179, 4345.739735476708, 5068.695878247298, 5110.744462776846, 5121.564667003309, 5141.074606570815, 4412.205802955466, 4418.094290670405, 4420.359047938063, 4426.778661066966, 4442.711223770477, 6560.803175904731, 6728.596284222962, 6739.966300168517, 6771.627560484171, 6839.350261725128, 5971.402859714038, 6028.216331876872, 6038.607490513103, 6042.456178943916, 6044.516438684938, 6426.710798014101, 7413.073365829271, 7413.592252426114, 7423.777286681408, 7437.256274372574, 4928.704277743311, 4942.950570494321, 4948.307236986825, 4971.328870602459, 4972.254161875711, 7079.929200707978, 7850.450468467209, 7852.411069169835, 7864.095629002681, 7886.881696774553, 4951.90096198074, 4985.0, 4989.650724449254, 5008.824691135793, 5015.749212539358, 5106.106431374569, 5108.131617625013, 5114.181730923035, 5124.30821839047, 5146.536811686966, 5946.43508866657, 5954.225950626391, 5975.462208401264, 5991.221141251696, 6012.218411606463, 7434.1079070510805, 7463.216362281991, 7474.514156579805, 7483.176850546435, 7496.512726672749, 1696.1180186303054, 1713.4345665930214, 1766.9823301766817, 1773.4413659697154, 1804.440623406748, 1141.8686851012073, 1152.5562658376418, 1167.3229526874393, 1168.1297433411992, 1168.7253495428567, 6872.175338959257, 6873.106496155568, 6875.0, 6889.311068893071, 6919.0, 7713.65288562016, 7717.0, 7725.652345644459, 7729.227077292226, 7782.143964163937, 6242.781502647476, 6846.246912839583, 6876.140482439695, 6884.690188941509, 6905.551139175937, 3060.4185204811015, 3369.629340772513, 3503.948815355383, 3839.2705385976574, 4114.670826333946, 6704.932950670431, 6852.972054191911, 7941.563214023168, 8030.437869349206, 8043.436959412785, 7660.387169026464, 7958.522488650699, 7965.725483922544, 7986.480878742979, 7994.360451163893, 7983.920160798318, 7990.960045199737, 7993.72021979228, 7997.920020799834, 8029.197080291808, 7535.095788505195, 7716.498427602188, 7720.845583088195, 7723.53658780475, 7790.259925307087, 6950.478714096362, 7605.885911711218, 7636.08366995964, 7674.386049116169, 7710.383169346326, 6610.438112760276, 8146.429749917449, 8154.1030486647605, 8176.305014073871, 8189.303909167787, 4862.440819305825, 4886.340344053508, 4889.657723959289, 4896.0, 4927.802887884444, 7549.433792465477, 7808.57052862098, 7828.647710853139, 7858.842823143619, 7862.253085956825, 7471.3649339806725, 7517.060367454068, 7542.170361260367, 7556.65995030236, 7561.621918903923, 7536.623168841646, 7542.094948606203, 7546.962265188639, 7557.0, 7567.962160189164, 7330.193678695233, 7343.3390994810725, 7346.926530734838, 7350.52221605594, 7374.151972523228, 7198.028266183998, 7527.849443011219, 7569.243075692387, 7580.241975802375, 7583.886241706489, 5608.775648973995, 7213.9278607213255, 7354.043974283309, 7358.1538123116525, 7398.778036658909], "unit": "score", "name": "prototype.html", "value": 5432.457931907272}, {"lowerIsBetter": false, "replicates": [701.6456689371863, 712.9786106416807, 730.813642521157, 736.7200463823746, 765.5789315876267, 15528.223588820556, 15813.367465301402, 15877.206139693013, 15886.682266354666, 15925.805564582659, 14649.121052736817, 14685.0, 14701.92649036756, 14726.926365368185, 14731.263436828169, 772.8338407242455, 953.8521529162991, 959.4770849886828, 985.8767654043245, 991.177322822056, 966.7293157915759, 980.7744218829673, 981.1562056631292, 981.3719219699398, 985.0, 1024.3137098144225, 1029.1458089785497, 1033.018632299315, 1033.6278939581744, 1035.3684252605904, 1023.5445226874044, 1026.1175389165312, 1029.8197815382277, 1030.5208078243616, 1035.3270374256717, 764.5718397697308, 769.93840492761, 769.9576523291216, 771.4522688890838, 772.7025095338279, 15214.771778423332, 15248.23758812066, 15533.766993495106, 16046.438374656831, 16068.839311606967, 13341.466341346335, 13380.19718816874, 13409.458917438726, 13429.932850335783, 13514.526991555347, 10352.482375881176, 10361.585536578528, 10416.947915260374, 10437.321574097736, 10545.683629491128, 3605.7115430765475, 3611.115276757211, 3622.800745958971, 3656.6708996190205, 3681.932239650498, 82415.5879220606, 84007.5799620998, 84954.15045849586, 84978.0, 85348.71976920351, 8294.626741796634, 9459.76350591227, 9601.183899368492, 9873.40759554429, 10036.548355324028, 23142.190023349438, 23305.300840974796, 23704.40738981539, 23810.404739881295, 24414.877925610257, 6550.8362290943105, 6750.628715420699, 7780.572068536285, 7787.143414224431, 8056.154103819132, 4135.255653982312, 4198.5591512891315, 4215.220184265921, 4286.292761694348, 4310.870673879788, 3766.37854753968, 3797.9810100949317, 3810.3522401191863, 3817.1411432428035, 3829.329867273216, 718.005562296213, 728.5847067171661, 732.6666366803091, 737.9741709040158, 743.84007438401, 545.6835035679296, 572.762303643983, 610.2158726037021, 623.2427600465456, 623.8970569856014, 8097.554634495042, 8571.0, 8615.61229744663, 8635.654573816977, 8645.691380764843, 550.5870597052211, 559.7397210297231, 602.3494625804121, 604.5465900574569, 646.1761254400583, 3921.411788231731, 4039.1921615676983, 4077.347624380144, 4088.264112459786, 4264.04059086703, 75757.0, 93588.0, 94612.0, 94650.52674736582, 95186.0, 79253.0, 79326.41347173024, 79376.0, 80404.0, 85383.71924421139, 73632.26367736254, 78359.0, 78945.60527197327, 79631.60184199041, 82915.5854220725, 8191.9350484436645, 8655.783605409915, 8819.0, 8995.73901729226, 9078.409903356262, 47125.528744712115, 50643.0, 52604.68488287823, 54139.0, 55071.0, 68240.63518729697, 70749.29250707426, 72015.0, 74171.62914185395, 74854.62572687102, 11316.547338106384, 11728.648140555797, 11838.881611184122, 11996.280223186637, 12045.457954392248, 12032.0, 12307.815382769444, 12321.075919306268, 12612.87387126117, 12622.55821046259, 10439.373637581466, 11625.24435911663, 11833.290002599528, 11871.643850684493, 12060.698482538006, 11579.418344519016, 11638.243514171552, 11822.29066255993, 11899.76200476003, 11949.22330048544, 11750.471228794553, 11779.175457717878, 11798.343270720625, 11997.100217483548, 12039.578614748441, 9985.800283994426, 10065.798684026426, 10076.496175191358, 10081.495925203564, 10308.896911030793, 8287.917120828955, 10076.294659373774, 10081.949590252001, 10107.949460252652, 10153.695389138338], "unit": "score", "name": "yui.html", "value": 7239.976218205027}], "name": "dromaeo_css", "value": 7706.26603717223}, {"lowerIsBetter": true, "subtests": [{"lowerIsBetter": true, "replicates": [124.0, 109.0, 115.0, 116.0, 116.0, 128.0, 114.0, 116.0, 118.0, 115.0], "unit": "score", "name": "ai-astar", "value": 117.1}, {"lowerIsBetter": true, "replicates": [149.0, 117.0, 139.0, 152.0, 115.0, 158.0, 131.0, 152.0, 185.0, 148.0], "unit": "score", "name": "audio-beat-detection", "value": 144.6}, {"lowerIsBetter": true, "replicates": [193.0, 195.0, 224.0, 226.0, 193.0, 219.0, 272.0, 205.0, 198.0, 199.0], "unit": "score", "name": "audio-dft", "value": 212.4}, {"lowerIsBetter": true, "replicates": [71.0, 68.0, 74.0, 110.0, 58.0, 56.0, 59.0, 58.0, 123.0, 92.0], "unit": "score", "name": "audio-fft", "value": 76.9}, {"lowerIsBetter": true, "replicates": [85.0, 80.0, 121.0, 77.0, 84.0, 82.0, 115.0, 79.0, 78.0, 122.0], "unit": "score", "name": "audio-oscillator", "value": 92.3}, {"lowerIsBetter": true, "replicates": [89.0, 88.0, 92.0, 91.0, 89.0, 86.0, 91.0, 87.0, 90.0, 107.0], "unit": "score", "name": "imaging-gaussian-blur", "value": 91.0}, {"lowerIsBetter": true, "replicates": [93.0, 95.0, 94.0, 94.0, 96.0, 94.0, 98.0, 96.0, 95.0, 95.0], "unit": "score", "name": "imaging-darkroom", "value": 95.0}, {"lowerIsBetter": true, "replicates": [90.0, 136.0, 142.0, 117.0, 93.0, 110.0, 147.0, 98.0, 90.0, 94.0], "unit": "score", "name": "imaging-desaturate", "value": 111.7}, {"lowerIsBetter": true, "replicates": [53.0, 58.0, 59.0, 59.0, 60.0, 60.0, 58.0, 66.0, 56.0, 54.0], "unit": "score", "name": "json-parse-financial", "value": 58.3}, {"lowerIsBetter": true, "replicates": [63.0, 63.0, 74.0, 76.0, 73.0, 70.0, 64.0, 66.0, 69.0, 65.0], "unit": "score", "name": "json-stringify-tinderbox", "value": 68.3}, {"lowerIsBetter": true, "replicates": [84.0, 88.0, 86.0, 83.0, 82.0, 84.0, 77.0, 83.0, 84.0, 85.0], "unit": "score", "name": "stanford-crypto-aes", "value": 83.6}, {"lowerIsBetter": true, "replicates": [150.0, 132.0, 134.0, 120.0, 119.0, 120.0, 119.0, 118.0, 123.0, 127.0], "unit": "score", "name": "stanford-crypto-ccm", "value": 126.2}, {"lowerIsBetter": true, "replicates": [136.0, 137.0, 136.0, 163.0, 172.0, 168.0, 137.0, 136.0, 137.0, 123.0], "unit": "score", "name": "stanford-crypto-pbkdf2", "value": 144.5}, {"lowerIsBetter": true, "replicates": [68.0, 66.0, 65.0, 79.0, 66.0, 85.0, 90.0, 81.0, 67.0, 64.0], "unit": "score", "name": "stanford-crypto-sha256-iterative", "value": 73.1}], "name": "kraken", "value": 1494.9999999999998}]} 04:01:25 INFO - Return code: 0 04:01:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:01:25 ERROR - # TBPL SUCCESS # 04:01:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:01:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:01:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:01:25 INFO - 2016-04-27 04:01:25.802 screenresolution[1683:10431] starting screenresolution argv=screenresolution get 04:01:25 INFO - 2016-04-27 04:01:25.805 screenresolution[1683:10431] Display 0: 1600x1200x32@60 04:01:25 INFO - 2016-04-27 04:01:25.811 screenresolution[1684:10434] starting screenresolution argv=screenresolution list 04:01:25 INFO - Available Modes on Display 0 04:01:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:01:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:01:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:01:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:01:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:01:26 INFO - Intel Iris: 04:01:26 INFO - Chipset Model: Intel Iris 04:01:26 INFO - Type: GPU 04:01:26 INFO - Bus: Built-In 04:01:26 INFO - VRAM (Dynamic, Max): 1536 MB 04:01:26 INFO - Vendor: Intel (0x8086) 04:01:26 INFO - Device ID: 0x0a2e 04:01:26 INFO - Revision ID: 0x0009 04:01:26 INFO - Displays: 04:01:26 INFO - Display: 04:01:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:01:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:01:26 INFO - Display Serial Number: 1600x1200 60 04:01:26 INFO - Main Display: Yes 04:01:26 INFO - Mirror: Off 04:01:26 INFO - Online: Yes 04:01:26 INFO - Rotation: Supported 04:01:26 INFO - Return code: 0 04:01:26 INFO - Running post-action listener: _resource_record_post_action 04:01:26 INFO - Running post-run listener: _resource_record_post_run 04:01:27 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 38127104; Write bytes: 664912896; Read time: 402; Write time: 2000 04:01:27 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 355115520; Write bytes: 354740224; Read time: 27836; Write time: 675 04:01:27 INFO - run-tests - Wall time: 1171s; CPU: 54.0%; Read bytes: 37152256; Write bytes: 299830272; Read time: 384; Write time: 1293 04:01:27 INFO - Running post-run listener: _upload_blobber_files 04:01:27 INFO - Blob upload gear active. 04:01:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:01:27 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 04:01:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:01:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:01:27 INFO - (blobuploader) - INFO - Open directory for files ... 04:01:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:01:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:01:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:28 INFO - (blobuploader) - INFO - Done attempting. 04:01:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:01:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:01:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:01:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:01:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:01:29 INFO - (blobuploader) - INFO - Done attempting. 04:01:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:01:29 INFO - Return code: 0 04:01:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:01:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:01:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e51645d6bccc4fc424b76f2d8c30c455a93e5edf6b58bca56e20b82df3bfded5dfe3e0980112d8315920f9aaca287f7dd93d15fc1b5a6e3572ae6b6b5e5892b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/664699c509d8f71ad1eeec7401e2fce0a60c1235ba82df565f4b4b28cea746c12d5eb398f15f941460bcfc1f14e295d42dc320cdc5bbab81e18b8b046092ede8"} 04:01:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:01:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:01:29 INFO - Contents: 04:01:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e51645d6bccc4fc424b76f2d8c30c455a93e5edf6b58bca56e20b82df3bfded5dfe3e0980112d8315920f9aaca287f7dd93d15fc1b5a6e3572ae6b6b5e5892b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/664699c509d8f71ad1eeec7401e2fce0a60c1235ba82df565f4b4b28cea746c12d5eb398f15f941460bcfc1f14e295d42dc320cdc5bbab81e18b8b046092ede8"} 04:01:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:01:29 INFO - Copying logs to upload dir... 04:01:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:01:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.930231 ========= master_lag: 0.03 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-04-27 04:01:29.727408) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:01:29.731348) ========= 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.AksNifb2mC/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.Hj1VBL1G3h/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e51645d6bccc4fc424b76f2d8c30c455a93e5edf6b58bca56e20b82df3bfded5dfe3e0980112d8315920f9aaca287f7dd93d15fc1b5a6e3572ae6b6b5e5892b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/664699c509d8f71ad1eeec7401e2fce0a60c1235ba82df565f4b4b28cea746c12d5eb398f15f941460bcfc1f14e295d42dc320cdc5bbab81e18b8b046092ede8"} build_url:https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010819 build_url: 'https://queue.taskcluster.net/v1/task/SQDTyWLnRTi58-v24Lw8jQ/artifacts/public/build/firefox-47.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/7e51645d6bccc4fc424b76f2d8c30c455a93e5edf6b58bca56e20b82df3bfded5dfe3e0980112d8315920f9aaca287f7dd93d15fc1b5a6e3572ae6b6b5e5892b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/664699c509d8f71ad1eeec7401e2fce0a60c1235ba82df565f4b4b28cea746c12d5eb398f15f941460bcfc1f14e295d42dc320cdc5bbab81e18b8b046092ede8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-04-27 04:01:29.759120) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:01:29.759495) ========= 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.AksNifb2mC/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.Hj1VBL1G3h/Listeners TMPDIR=/var/folders/s_/41fvm5ld6bb_qpjwgs6r2cd800000w/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.005115 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-04-27 04:01:29.808802) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:01:29.809489) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-04-27 04:01:29.810328) ========= ========= Total master_lag: 0.60 =========