builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0170 starttime: 1467889594.89 results: success (0) buildid: 20160706215822 builduid: 005acac198204b229cc2500b323a4168 revision: d142c49033c015f67272562b37dbe2912cfc7f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:34.893983) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:34.895049) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:34.895737) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:34.922371) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:34.923003) ========= 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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-07-07 04:06:34-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.7M=0.001s 2016-07-07 04:06:35 (17.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.129635 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:35.076998) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:35.077362) ========= 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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.028109 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:35.128494) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 04:06:35.129288) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d142c49033c015f67272562b37dbe2912cfc7f14 --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-beta --rev d142c49033c015f67272562b37dbe2912cfc7f14 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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-07-07 04:06:35,193 truncating revision to first 12 chars 2016-07-07 04:06:35,193 Setting DEBUG logging. 2016-07-07 04:06:35,193 attempt 1/10 2016-07-07 04:06:35,193 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d142c49033c0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-07 04:06:36,299 unpacking tar archive at: mozilla-beta-d142c49033c0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.373138 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-07 04:06:36.528617) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:36.529442) ========= script_repo_revision: d142c49033c015f67272562b37dbe2912cfc7f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:36.530142) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:36.530446) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-07 04:06:36.549538) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-07-07 04:06:36.550069) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:06:36 INFO - MultiFileLogger online at 20160707 04:06:36 in /builds/slave/test 04:06:36 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 04:06:36 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:06:36 INFO - {'append_to_log': False, 04:06:36 INFO - 'base_work_dir': '/builds/slave/test', 04:06:36 INFO - 'blob_upload_branch': 'Mozilla-Beta', 04:06:36 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:06:36 INFO - 'branch': 'Mozilla-Beta', 04:06:36 INFO - 'buildbot_json_path': 'buildprops.json', 04:06:36 INFO - 'config_files': ('talos/mac_config.py',), 04:06:36 INFO - 'default_actions': ('clobber', 04:06:36 INFO - 'read-buildbot-config', 04:06:36 INFO - 'download-and-extract', 04:06:36 INFO - 'populate-webroot', 04:06:36 INFO - 'create-virtualenv', 04:06:36 INFO - 'install', 04:06:36 INFO - 'run-tests'), 04:06:36 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:06:36 INFO - 'download_minidump_stackwalk': True, 04:06:36 INFO - 'download_symbols': 'ondemand', 04:06:36 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:06:36 INFO - 'tooltool.py': '/tools/tooltool.py', 04:06:36 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:06:36 INFO - '/tools/misc-python/virtualenv.py')}, 04:06:36 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:06:36 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:06:36 INFO - 'installer_path': 'installer.exe', 04:06:36 INFO - 'log_level': 'info', 04:06:36 INFO - 'log_name': 'talos', 04:06:36 INFO - 'log_to_console': True, 04:06:36 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:06:36 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:06:36 INFO - 'opt_config_files': (), 04:06:36 INFO - 'pip_index': False, 04:06:36 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:06:36 INFO - 'cmd': ('bash', 04:06:36 INFO - '-c', 04:06:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:06:36 INFO - 'enabled': True, 04:06:36 INFO - 'halt_on_failure': False, 04:06:36 INFO - 'name': 'check_screen_resolution'},), 04:06:36 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:06:36 INFO - 'cmd': ('bash', 04:06:36 INFO - '-c', 04:06:36 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:06:36 INFO - 'enabled': True, 04:06:36 INFO - 'halt_on_failure': False, 04:06:36 INFO - 'name': 'check_screen_resolution'},), 04:06:36 INFO - 'run_cmd_checks_enabled': True, 04:06:36 INFO - 'sps_profile': False, 04:06:36 INFO - 'sps_profile_interval': 0, 04:06:36 INFO - 'suite': 'dromaeojs', 04:06:36 INFO - 'system_bits': '32', 04:06:36 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:06:36 INFO - 'title': 't-yosemite-r7-0170', 04:06:36 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:06:36 INFO - 'use_talos_json': True, 04:06:36 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:06:36 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:06:36 INFO - 'work_dir': 'build'} 04:06:36 INFO - ##### 04:06:36 INFO - ##### Running clobber step. 04:06:36 INFO - ##### 04:06:36 INFO - Running pre-action listener: _resource_record_pre_action 04:06:36 INFO - Running main action method: clobber 04:06:36 INFO - rmtree: /builds/slave/test/build 04:06:36 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:06:39 INFO - Running post-action listener: _resource_record_post_action 04:06:39 INFO - ##### 04:06:39 INFO - ##### Running read-buildbot-config step. 04:06:39 INFO - ##### 04:06:39 INFO - Running pre-action listener: _resource_record_pre_action 04:06:39 INFO - Running main action method: read_buildbot_config 04:06:39 INFO - Using buildbot properties: 04:06:39 INFO - { 04:06:39 INFO - "project": "", 04:06:39 INFO - "product": "firefox", 04:06:39 INFO - "script_repo_revision": "production", 04:06:39 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 04:06:39 INFO - "repository": "", 04:06:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 04:06:39 INFO - "buildid": "20160706215822", 04:06:39 INFO - "pgo_build": "False", 04:06:39 INFO - "basedir": "/builds/slave/test", 04:06:39 INFO - "buildnumber": 61, 04:06:39 INFO - "slavename": "t-yosemite-r7-0170", 04:06:39 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 04:06:39 INFO - "platform": "yosemite_r7", 04:06:39 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 04:06:39 INFO - "branch": "mozilla-beta", 04:06:39 INFO - "repo_path": "releases/mozilla-beta", 04:06:39 INFO - "revision": "d142c49033c015f67272562b37dbe2912cfc7f14", 04:06:39 INFO - "stage_platform": "macosx64", 04:06:39 INFO - "builduid": "005acac198204b229cc2500b323a4168", 04:06:39 INFO - "slavebuilddir": "test" 04:06:39 INFO - } 04:06:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 04:06:39 INFO - Running post-action listener: _resource_record_post_action 04:06:39 INFO - ##### 04:06:39 INFO - ##### Running download-and-extract step. 04:06:39 INFO - ##### 04:06:39 INFO - Running pre-action listener: _resource_record_pre_action 04:06:39 INFO - Running main action method: download_and_extract 04:06:39 INFO - mkdir: /builds/slave/test/build/tests 04:06:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:06:39 INFO - https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:06:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 04:06:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 04:06:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 04:06:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json'}, attempt #1 04:06:42 INFO - Downloaded 1165 bytes. 04:06:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 04:06:42 INFO - Using the following test package requirements: 04:06:42 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 04:06:42 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 04:06:42 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 04:06:42 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 04:06:42 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 04:06:42 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 04:06:42 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 04:06:42 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 04:06:42 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 04:06:42 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 04:06:42 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 04:06:42 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 04:06:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:06:42 INFO - https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:06:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:06:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:06:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 04:06:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 04:06:43 INFO - Downloaded 18351388 bytes. 04:06:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:06:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:06:46 INFO - Return code: 0 04:06:46 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip', u'firefox-48.0.en-US.mac.talos.tests.zip'] for test suite category: talos 04:06:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:06:46 INFO - https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:06:46 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:06:46 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 04:06:46 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip 04:06:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip'}, attempt #1 04:06:48 INFO - Downloaded 18351388 bytes. 04:06:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:06:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:06:51 INFO - Return code: 0 04:06:51 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')]} 04:06:51 INFO - https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:06:51 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 04:06:51 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 04:06:51 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip 04:06:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip'}, attempt #1 04:06:52 INFO - Downloaded 10840145 bytes. 04:06:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:06:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:06:52 INFO - Return code: 0 04:06: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')]} 04:06:52 INFO - https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 04:06:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:06:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:06:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 04:06:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg'}, attempt #1 04:06:55 INFO - Downloaded 86189077 bytes. 04:06:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:06:55 INFO - mkdir: /builds/slave/test/properties 04:06:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:06:55 INFO - Writing to file /builds/slave/test/properties/build_url 04:06:55 INFO - Contents: 04:06:55 INFO - build_url:https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 04:06:56 INFO - Running post-action listener: _resource_record_post_action 04:06:56 INFO - Running post-action listener: set_extra_try_arguments 04:06:56 INFO - ##### 04:06:56 INFO - ##### Running populate-webroot step. 04:06:56 INFO - ##### 04:06:56 INFO - Running pre-action listener: _resource_record_pre_action 04:06:56 INFO - Running main action method: populate_webroot 04:06:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:06:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:06:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:06:56 INFO - '--tppagecycles', 04:06:56 INFO - '7'], 04:06:56 INFO - 'tests': ['tsvgm']}}, 04:06:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:06:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:06:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:06:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:06:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:06:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:06:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['damp', 'tps']}, 04:06:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'tests': ['damp', 'tps']}, 04:06:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['dromaeo_dom']}, 04:06:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:06:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['a11yr', 04:06:56 INFO - 'ts_paint', 04:06:56 INFO - 'tpaint', 04:06:56 INFO - 'sessionrestore', 04:06:56 INFO - 'sessionrestore_no_auto_restore', 04:06:56 INFO - 'tabpaint']}, 04:06:56 INFO - 'other-e10s': {'tests': ['a11yr', 04:06:56 INFO - 'ts_paint', 04:06:56 INFO - 'tpaint', 04:06:56 INFO - 'sessionrestore', 04:06:56 INFO - 'sessionrestore_no_auto_restore', 04:06:56 INFO - 'tabpaint']}, 04:06:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:06:56 INFO - 'ts_paint', 04:06:56 INFO - 'tpaint', 04:06:56 INFO - 'sessionrestore', 04:06:56 INFO - 'sessionrestore_no_auto_restore', 04:06:56 INFO - 'tabpaint']}, 04:06:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:06:56 INFO - 'ts_paint', 04:06:56 INFO - 'tpaint', 04:06:56 INFO - 'sessionrestore', 04:06:56 INFO - 'sessionrestore_no_auto_restore', 04:06:56 INFO - 'tabpaint']}, 04:06:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['a11yr', 04:06:56 INFO - 'ts_paint', 04:06:56 INFO - 'tpaint', 04:06:56 INFO - 'sessionrestore', 04:06:56 INFO - 'sessionrestore_no_auto_restore', 04:06:56 INFO - 'tabpaint']}, 04:06:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['a11yr', 04:06:56 INFO - 'ts_paint', 04:06:56 INFO - 'tpaint', 04:06:56 INFO - 'sessionrestore', 04:06:56 INFO - 'sessionrestore_no_auto_restore', 04:06:56 INFO - 'tabpaint']}, 04:06:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['tsvgx', 04:06:56 INFO - 'tsvgr_opacity', 04:06:56 INFO - 'tart', 04:06:56 INFO - 'tscrollx', 04:06:56 INFO - 'cart']}, 04:06:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:06:56 INFO - 'tsvgr_opacity', 04:06:56 INFO - 'tart', 04:06:56 INFO - 'tscrollx', 04:06:56 INFO - 'cart']}, 04:06:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'talos_options': ['--disable-e10s'], 04:06:56 INFO - 'tests': ['tp5o']}, 04:06:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'tests': ['tp5o']}, 04:06:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'talos_options': ['--disable-e10s', 04:06:56 INFO - '--xperf_path', 04:06:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:06:56 INFO - 'tests': ['tp5n']}, 04:06:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:06:56 INFO - 'talos_options': ['--xperf_path', 04:06:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:06:56 INFO - 'tests': ['tp5n']}}, 04:06:56 INFO - 'talos.zip': {'path': '', 04:06:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:06:56 INFO - Running post-action listener: _resource_record_post_action 04:06:56 INFO - ##### 04:06:56 INFO - ##### Running create-virtualenv step. 04:06:56 INFO - ##### 04:06:56 INFO - Running pre-action listener: _resource_record_pre_action 04:06:56 INFO - Running main action method: create_virtualenv 04:06:56 INFO - Creating virtualenv /builds/slave/test/build/venv 04:06:56 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 04:06:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:06:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:06:56 INFO - Using real prefix '/tools/python27' 04:06:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:06:57 INFO - Installing distribute.............................................................................................................................................................................................done. 04:07:00 INFO - Installing pip.................done. 04:07:00 INFO - Return code: 0 04:07:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:07: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')]} 04:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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')]} 04:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07: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', 'pip>=1.5'] in /builds/slave/test/build 04:07: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 pip>=1.5 04:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:00 INFO - 'HOME': '/Users/cltbld', 04:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:00 INFO - 'LOGNAME': 'cltbld', 04:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:00 INFO - 'MOZ_NO_REMOTE': '1', 04:07:00 INFO - 'NO_EM_RESTART': '1', 04:07:00 INFO - 'PAGER': '/bin/cat', 04:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:00 INFO - 'PWD': '/builds/slave/test', 04:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:00 INFO - 'SHELL': '/bin/bash', 04:07:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:00 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:00 INFO - 'USER': 'cltbld', 04:07:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:00 INFO - 'XPC_FLAGS': '0x0', 04:07:00 INFO - 'XPC_SERVICE_NAME': '0', 04:07:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:00 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 04:07:00 INFO - Cleaning up... 04:07:00 INFO - Return code: 0 04:07:00 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:07: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')]} 04:07:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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')]} 04:07:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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', '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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07: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', 'psutil>=0.7.1'] in /builds/slave/test/build 04:07: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 psutil>=0.7.1 04:07:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:00 INFO - 'HOME': '/Users/cltbld', 04:07:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:00 INFO - 'LOGNAME': 'cltbld', 04:07:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:00 INFO - 'MOZ_NO_REMOTE': '1', 04:07:00 INFO - 'NO_EM_RESTART': '1', 04:07:00 INFO - 'PAGER': '/bin/cat', 04:07:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:00 INFO - 'PWD': '/builds/slave/test', 04:07:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:00 INFO - 'SHELL': '/bin/bash', 04:07:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:00 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:00 INFO - 'USER': 'cltbld', 04:07:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:00 INFO - 'XPC_FLAGS': '0x0', 04:07:00 INFO - 'XPC_SERVICE_NAME': '0', 04:07:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:00 INFO - Downloading/unpacking psutil>=0.7.1 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:07:03 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:07:03 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:07:03 INFO - Installing collected packages: psutil 04:07:03 INFO - Running setup.py install for psutil 04:07:03 INFO - building 'psutil._psutil_osx' extension 04:07:03 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 04:07:04 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 04:07:04 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 04:07:04 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 04:07:04 INFO - building 'psutil._psutil_posix' extension 04:07:04 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 04:07:04 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:07:04 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:07:04 INFO - ^ 04:07:04 INFO - 1 warning generated. 04:07:04 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 04:07:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:07:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:07:04 INFO - Successfully installed psutil 04:07:04 INFO - Cleaning up... 04:07:04 INFO - Return code: 0 04:07:04 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:07: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')]} 04:07:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07: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')]} 04:07:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:04 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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:04 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 04:07:04 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 04:07:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:04 INFO - 'HOME': '/Users/cltbld', 04:07:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:04 INFO - 'LOGNAME': 'cltbld', 04:07:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:04 INFO - 'MOZ_NO_REMOTE': '1', 04:07:04 INFO - 'NO_EM_RESTART': '1', 04:07:04 INFO - 'PAGER': '/bin/cat', 04:07:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:04 INFO - 'PWD': '/builds/slave/test', 04:07:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:04 INFO - 'SHELL': '/bin/bash', 04:07:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:04 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:04 INFO - 'USER': 'cltbld', 04:07:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:04 INFO - 'XPC_FLAGS': '0x0', 04:07:04 INFO - 'XPC_SERVICE_NAME': '0', 04:07:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:05 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:07:05 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 04:07:05 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 04:07:05 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 04:07:05 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 04:07:05 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 04:07:05 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 04:07:07 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:07:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:07:07 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:07:07 INFO - Installing collected packages: mozsystemmonitor 04:07:07 INFO - Running setup.py install for mozsystemmonitor 04:07:07 INFO - Successfully installed mozsystemmonitor 04:07:07 INFO - Cleaning up... 04:07:07 INFO - Return code: 0 04:07:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:07 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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:07 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 04:07:07 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 04:07:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:07 INFO - 'HOME': '/Users/cltbld', 04:07:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:07 INFO - 'LOGNAME': 'cltbld', 04:07:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:07 INFO - 'MOZ_NO_REMOTE': '1', 04:07:07 INFO - 'NO_EM_RESTART': '1', 04:07:07 INFO - 'PAGER': '/bin/cat', 04:07:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:07 INFO - 'PWD': '/builds/slave/test', 04:07:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:07 INFO - 'SHELL': '/bin/bash', 04:07:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:07 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:07 INFO - 'USER': 'cltbld', 04:07:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:07 INFO - 'XPC_FLAGS': '0x0', 04:07:07 INFO - 'XPC_SERVICE_NAME': '0', 04:07:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:08 INFO - Downloading/unpacking blobuploader==1.2.4 04:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:10 INFO - Downloading blobuploader-1.2.4.tar.gz 04:07:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:07:10 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:07:10 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 04:07:10 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 04:07:10 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 04:07:10 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 04:07:10 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 04:07:10 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 04:07:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:07:11 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07: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 04:07:11 INFO - Downloading docopt-0.6.1.tar.gz 04:07:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:07:12 INFO - Installing collected packages: blobuploader, requests, docopt 04:07:12 INFO - Running setup.py install for blobuploader 04:07:12 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:07:12 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:07:12 INFO - Running setup.py install for requests 04:07:12 INFO - Running setup.py install for docopt 04:07:12 INFO - Successfully installed blobuploader requests docopt 04:07:12 INFO - Cleaning up... 04:07:12 INFO - Return code: 0 04:07:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:12 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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:12 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 04:07:12 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 04:07:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:12 INFO - 'HOME': '/Users/cltbld', 04:07:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:12 INFO - 'LOGNAME': 'cltbld', 04:07:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:12 INFO - 'MOZ_NO_REMOTE': '1', 04:07:12 INFO - 'NO_EM_RESTART': '1', 04:07:12 INFO - 'PAGER': '/bin/cat', 04:07:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:12 INFO - 'PWD': '/builds/slave/test', 04:07:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:12 INFO - 'SHELL': '/bin/bash', 04:07:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:12 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:12 INFO - 'USER': 'cltbld', 04:07:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:12 INFO - 'XPC_FLAGS': '0x0', 04:07:12 INFO - 'XPC_SERVICE_NAME': '0', 04:07:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-FWZ2AD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-zkSJnP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-cgyqAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-vf5YDT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-VIMVXh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-PM7enV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-c2DnKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-iHlk1g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:07:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:07:13 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-EbIOKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-Zf_zaX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-oV9NCH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-LtwfjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-fAWw3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-b1GFk6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-M9BUnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-A7vpDw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:07:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:07:14 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-2C4DNd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:07:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:07:14 INFO - Running setup.py install for manifestparser 04:07:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:07:15 INFO - Running setup.py install for mozcrash 04:07:15 INFO - Running setup.py install for mozdebug 04:07:15 INFO - Running setup.py install for mozdevice 04:07:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:07:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:07:15 INFO - Running setup.py install for mozfile 04:07:15 INFO - Running setup.py install for mozhttpd 04:07:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:07:15 INFO - Running setup.py install for mozinfo 04:07:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:07:15 INFO - Running setup.py install for mozInstall 04:07:16 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Running setup.py install for mozleak 04:07:16 INFO - Running setup.py install for mozlog 04:07:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Running setup.py install for moznetwork 04:07:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Running setup.py install for mozprocess 04:07:16 INFO - Running setup.py install for mozprofile 04:07:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:07:16 INFO - Running setup.py install for mozrunner 04:07:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:07:17 INFO - Running setup.py install for mozscreenshot 04:07:17 INFO - Running setup.py install for moztest 04:07:17 INFO - Running setup.py install for mozversion 04:07:17 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:07:17 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:07:17 INFO - Cleaning up... 04:07:17 INFO - Return code: 0 04:07:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:17 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:07:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:07:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:17 INFO - 'HOME': '/Users/cltbld', 04:07:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:17 INFO - 'LOGNAME': 'cltbld', 04:07:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:17 INFO - 'MOZ_NO_REMOTE': '1', 04:07:17 INFO - 'NO_EM_RESTART': '1', 04:07:17 INFO - 'PAGER': '/bin/cat', 04:07:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:17 INFO - 'PWD': '/builds/slave/test', 04:07:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:17 INFO - 'SHELL': '/bin/bash', 04:07:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:17 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:17 INFO - 'USER': 'cltbld', 04:07:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:17 INFO - 'XPC_FLAGS': '0x0', 04:07:17 INFO - 'XPC_SERVICE_NAME': '0', 04:07:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:07:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-PggnAk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:07:17 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:07:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-o76H9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:07:17 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)) 04:07:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:07:17 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-uEEVB0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1uBy36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-n9MhzM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-jD19iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-jfHghX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-6oAatx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-s5zyPZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-2BW7Ae-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-TP2WD5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:07:18 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-CLYbjL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:07:18 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:07:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:07:19 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-smBaN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:07:19 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)) 04:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:07:19 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-1EhhSr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:07:19 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)) 04:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:19 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-cjQ4Br-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:07:19 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)) 04:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:07:19 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-J8kz39-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:07:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:07:19 INFO - Running setup.py (path:/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/pip-y6lhzz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:07:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:07:19 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:21 INFO - Downloading blessings-1.6.tar.gz 04:07:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:07:22 INFO - Installing collected packages: blessings 04:07:22 INFO - Running setup.py install for blessings 04:07:22 INFO - Successfully installed blessings 04:07:22 INFO - Cleaning up... 04:07:22 INFO - Return code: 0 04:07:22 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:07:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:07:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:07:22 INFO - Reading from file tmpfile_stdout 04:07:22 INFO - Current package versions: 04:07:22 INFO - blessings == 1.6 04:07:22 INFO - blobuploader == 1.2.4 04:07:22 INFO - docopt == 0.6.1 04:07:22 INFO - manifestparser == 1.1 04:07:22 INFO - mozInstall == 1.12 04:07:22 INFO - mozcrash == 0.17 04:07:22 INFO - mozdebug == 0.1 04:07:22 INFO - mozdevice == 0.48 04:07:22 INFO - mozfile == 1.2 04:07:22 INFO - mozhttpd == 0.7 04:07:22 INFO - mozinfo == 0.9 04:07:22 INFO - mozleak == 0.1 04:07:22 INFO - mozlog == 3.1 04:07:22 INFO - moznetwork == 0.27 04:07:22 INFO - mozprocess == 0.22 04:07:22 INFO - mozprofile == 0.28 04:07:22 INFO - mozrunner == 6.11 04:07:22 INFO - mozscreenshot == 0.1 04:07:22 INFO - mozsystemmonitor == 0.0 04:07:22 INFO - moztest == 0.7 04:07:22 INFO - mozversion == 1.4 04:07:22 INFO - psutil == 3.1.1 04:07:22 INFO - requests == 1.2.3 04:07:22 INFO - wsgiref == 0.1.2 04:07:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:07:22 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')]} 04:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:22 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')]} 04:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:22 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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:22 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 04:07:22 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 04:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:22 INFO - 'HOME': '/Users/cltbld', 04:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:22 INFO - 'LOGNAME': 'cltbld', 04:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:22 INFO - 'MOZ_NO_REMOTE': '1', 04:07:22 INFO - 'NO_EM_RESTART': '1', 04:07:22 INFO - 'PAGER': '/bin/cat', 04:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:22 INFO - 'PWD': '/builds/slave/test', 04:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:22 INFO - 'SHELL': '/bin/bash', 04:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:22 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:22 INFO - 'USER': 'cltbld', 04:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:22 INFO - 'XPC_FLAGS': '0x0', 04:07:22 INFO - 'XPC_SERVICE_NAME': '0', 04:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 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)) 04:07:22 INFO - Cleaning up... 04:07:22 INFO - Return code: 0 04:07:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:07:22 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')]} 04:07:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:22 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')]} 04:07:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:22 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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:22 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 04:07:22 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 04:07:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:22 INFO - 'HOME': '/Users/cltbld', 04:07:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:22 INFO - 'LOGNAME': 'cltbld', 04:07:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:22 INFO - 'MOZ_NO_REMOTE': '1', 04:07:22 INFO - 'NO_EM_RESTART': '1', 04:07:22 INFO - 'PAGER': '/bin/cat', 04:07:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:22 INFO - 'PWD': '/builds/slave/test', 04:07:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:22 INFO - 'SHELL': '/bin/bash', 04:07:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:22 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:22 INFO - 'USER': 'cltbld', 04:07:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:22 INFO - 'XPC_FLAGS': '0x0', 04:07:22 INFO - 'XPC_SERVICE_NAME': '0', 04:07:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:23 INFO - Downloading/unpacking jsonschema 04:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:07:25 INFO - Downloading/unpacking functools32 (from jsonschema) 04:07:25 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 04:07:25 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 04:07:25 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 04:07:25 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 04:07:25 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 04:07:25 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 04:07:25 INFO - Downloading functools32-3.2.3-2.tar.gz 04:07:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:07:26 INFO - warning: no files found matching '*.txt' 04:07:26 INFO - no previously-included directories found matching 'build' 04:07:26 INFO - no previously-included directories found matching 'dist' 04:07:26 INFO - no previously-included directories found matching '.git*' 04:07:26 INFO - Installing collected packages: jsonschema, functools32 04:07:26 INFO - Running setup.py install for functools32 04:07:26 INFO - warning: no files found matching '*.txt' 04:07:26 INFO - no previously-included directories found matching 'build' 04:07:26 INFO - no previously-included directories found matching 'dist' 04:07:26 INFO - no previously-included directories found matching '.git*' 04:07:26 INFO - Successfully installed jsonschema functools32 04:07:26 INFO - Cleaning up... 04:07:26 INFO - Return code: 0 04:07:26 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:07:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:07:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:07:26 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 0x1104e0a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1104f0420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f900bec0260>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, '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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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 04:07:26 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 04:07:26 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 04:07:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:26 INFO - 'HOME': '/Users/cltbld', 04:07:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:26 INFO - 'LOGNAME': 'cltbld', 04:07:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:26 INFO - 'MOZ_NO_REMOTE': '1', 04:07:26 INFO - 'NO_EM_RESTART': '1', 04:07:26 INFO - 'PAGER': '/bin/cat', 04:07:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:26 INFO - 'PWD': '/builds/slave/test', 04:07:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:26 INFO - 'SHELL': '/bin/bash', 04:07:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:26 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:26 INFO - 'USER': 'cltbld', 04:07:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:26 INFO - 'XPC_FLAGS': '0x0', 04:07:26 INFO - 'XPC_SERVICE_NAME': '0', 04:07:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:07:26 INFO - Downloading/unpacking flake8 04:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:07:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:07:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:07:29 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:07:29 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:07:29 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:29 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 04:07:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:07:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:07:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:07:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:07:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:07:30 INFO - no previously-included directories found matching 'docs/_build' 04:07:30 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:07:30 INFO - Running setup.py install for pep8 04:07:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:07:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:07:30 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:07:30 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:07:30 INFO - no previously-included directories found matching 'docs/_build' 04:07:30 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:07:30 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:07:30 INFO - Cleaning up... 04:07:30 INFO - Return code: 0 04:07:30 INFO - Running post-action listener: _resource_record_post_action 04:07:30 INFO - Running post-action listener: _start_resource_monitoring 04:07:30 INFO - Starting resource monitoring. 04:07:30 INFO - ##### 04:07:30 INFO - ##### Running install step. 04:07:30 INFO - ##### 04:07:30 INFO - Running pre-action listener: _resource_record_pre_action 04:07:30 INFO - Running main action method: install 04:07:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:07:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:07:31 INFO - Reading from file tmpfile_stdout 04:07:31 INFO - Detecting whether we're running mozinstall >=1.0... 04:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:07:31 INFO - Reading from file tmpfile_stdout 04:07:31 INFO - Output received: 04:07:31 INFO - Usage: mozinstall [options] installer 04:07:31 INFO - Options: 04:07:31 INFO - -h, --help show this help message and exit 04:07:31 INFO - -d DEST, --destination=DEST 04:07:31 INFO - Directory to install application into. [default: 04:07:31 INFO - "/builds/slave/test"] 04:07:31 INFO - --app=APP Application being installed. [default: firefox] 04:07:31 INFO - mkdir: /builds/slave/test/build/application 04:07:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:07:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0.en-US.mac.dmg --destination /builds/slave/test/build/application 04:07:52 INFO - Reading from file tmpfile_stdout 04:07:52 INFO - Output received: 04:07:52 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 04:07:52 INFO - Running post-action listener: _resource_record_post_action 04:07:52 INFO - ##### 04:07:52 INFO - ##### Running run-tests step. 04:07:52 INFO - ##### 04:07:52 INFO - Running pre-action listener: _resource_record_pre_action 04:07:52 INFO - Running main action method: run_tests 04:07:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:07:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:07:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:07:52 INFO - 2016-07-07 04:07:52.808 screenresolution[1638:7546] starting screenresolution argv=screenresolution get 04:07:52 INFO - 2016-07-07 04:07:52.811 screenresolution[1638:7546] Display 0: 1600x1200x32@60 04:07:52 INFO - 2016-07-07 04:07:52.817 screenresolution[1639:7548] starting screenresolution argv=screenresolution list 04:07:52 INFO - Available Modes on Display 0 04:07:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:07:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:07:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:07:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:07:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:07:53 INFO - Intel Iris: 04:07:53 INFO - Chipset Model: Intel Iris 04:07:53 INFO - Type: GPU 04:07:53 INFO - Bus: Built-In 04:07:53 INFO - VRAM (Dynamic, Max): 1536 MB 04:07:53 INFO - Vendor: Intel (0x8086) 04:07:53 INFO - Device ID: 0x0a2e 04:07:53 INFO - Revision ID: 0x0009 04:07:53 INFO - Displays: 04:07:53 INFO - Display: 04:07:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:07:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:07:53 INFO - Display Serial Number: 1600x1200 60 04:07:53 INFO - Main Display: Yes 04:07:53 INFO - Mirror: Off 04:07:53 INFO - Online: Yes 04:07:53 INFO - Rotation: Supported 04:07:53 INFO - Return code: 0 04:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:07:53 INFO - Python 2.7.3 04:07:53 INFO - Return code: 0 04:07:53 INFO - grabbing minidump binary from tooltool 04:07:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:07:53 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 0x11037fe00>, '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 0x1101e8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x11037ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:07:53 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 04:07:53 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 04:07:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:07:53 INFO - Return code: 0 04:07:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:07:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:07:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:07:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:07:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:07:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:07:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:07:54 INFO - Return code: 0 04:07:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 04:07:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0170 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 04:07:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Hw2T5YXHMG/Render', 04:07:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:07:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:07:54 INFO - 'HOME': '/Users/cltbld', 04:07:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:07:54 INFO - 'LOGNAME': 'cltbld', 04:07:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:07:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:07:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:07:54 INFO - 'MOZ_NO_REMOTE': '1', 04:07:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:07:54 INFO - 'NO_EM_RESTART': '1', 04:07:54 INFO - 'PAGER': '/bin/cat', 04:07:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:07:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:07:54 INFO - 'PWD': '/builds/slave/test', 04:07:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:07:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:07:54 INFO - 'SHELL': '/bin/bash', 04:07:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Mtxlra5KDQ/Listeners', 04:07:54 INFO - 'TMPDIR': '/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/', 04:07:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:07:54 INFO - 'USER': 'cltbld', 04:07:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:07:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:07:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:07:54 INFO - 'XPC_FLAGS': '0x0', 04:07:54 INFO - 'XPC_SERVICE_NAME': '0', 04:07:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:07:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0170', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 04:07:54 INFO - mozversion application_buildid: 20160706215822 04:07:54 INFO - mozversion application_changeset: d142c49033c015f67272562b37dbe2912cfc7f14 04:07:54 INFO - mozversion application_display_name: Firefox 04:07:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:07:54 INFO - mozversion application_name: Firefox 04:07:54 INFO - mozversion application_remotingname: firefox 04:07:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 04:07:54 INFO - mozversion application_vendor: Mozilla 04:07:54 INFO - mozversion application_version: 48.0 04:07:54 INFO - mozversion platform_buildid: 20160706215822 04:07:54 INFO - mozversion platform_changeset: d142c49033c015f67272562b37dbe2912cfc7f14 04:07:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 04:07:54 INFO - mozversion platform_version: 48.0 04:07:54 INFO - using testdate: 1467889674 04:07:54 INFO - actual date: 1467889674 04:07:54 INFO - starting webserver on 'localhost:49279' 04:07:54 INFO - SUITE-START | Running 2 tests 04:07:54 INFO - TEST-START | dromaeo_css 04:07:54 INFO - operating with platform_type : mac_ 04:07:54 INFO - Initialising browser for dromaeo_css test... 04:07:54 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpIsV5AD/profile http://localhost:49279/getInfo.html) 04:07:56 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 04:07:56 INFO - PROCESS | 1657 | colorDepth:24 04:07:56 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 04:07:56 INFO - PROCESS | 1657 | __metrics 04:07:56 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:07:56 ERROR - PROCESS | 1657 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:07:57 INFO - TEST-INFO | 1657: exit 0 04:07:57 INFO - Browser initialized. 04:07:57 INFO - Running cycle 1/1 for dromaeo_css test... 04:07:57 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmpIsV5AD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:08:09 INFO - PROCESS | 1660 | RSS: Main: 161759232 04:08:09 INFO - PROCESS | 1660 | 04:09:00 INFO - PROCESS | 1660 | 1467889740493 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] 04:11:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 04:11:11 INFO - PROCESS | 1660 | RSS: Main: 303026176 04:11:11 INFO - PROCESS | 1660 | 04:14:14 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 04:14:15 INFO - PROCESS | 1660 | RSS: Main: 305410048 04:14:15 INFO - PROCESS | 1660 | 04:17:17 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 04:17:17 INFO - PROCESS | 1660 | RSS: Main: 311787520 04:17:17 INFO - PROCESS | 1660 | 04:20:19 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 04:20:20 INFO - PROCESS | 1660 | RSS: Main: 300601344 04:20:20 INFO - PROCESS | 1660 | 04:23:23 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 04:23:24 INFO - PROCESS | 1660 | RSS: Main: 269201408 04:23:24 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 04:26:26 INFO - PROCESS | 1660 | RSS: Main: 314249216 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | __start_tp_report 04:26:26 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 04:26:26 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 04:26:26 INFO - PROCESS | 1660 | |i|pagename|runs| 04:26:26 INFO - PROCESS | 1660 | |0;dojo.html;3486.9999999999995;3565.857365705372;3599.712023038157;3626.4197728363465;3692.7415080944343;5719.056355701309;5802.477777000069;5828.795992140276;5828.854278643036;5845.094010428385;5860.619059761112;5866.413358664131;5876.177335173079;5881.911771323434;5903.675297858626;4306.720063195898;5184.818531351403;5260.368755749316;5267;5375.43557926416;5319.521243088121;5366.248725178478;5367.1680889462195;5371.973140134294;5373.113436283009;5308.071087559681;5344.331958505187;5344.4922732340365;5352.437994010631;5367.758450869701;5333.786648534074;5385.649932754359;5393.460653934575;5410.08028635133;5431.158170483582;5165.1219292720325;5209.765560549784;5315.255864179018;5316.20256961455;5338;5690.943090569082;5743.913841292384;5765.538756899438;5772.711364431803;5777.277840269966;6321;6322;6344.809655710336;6356.269029061671;6363.61818290904;6248.625082495065;6255.624662520263;6300.338464461257;6305.716242769086;6326.5255105867245;5943.692899264243;5961.2846458425265;5962.37395073511;5974;5975.073863551156;6385.13800636908;6391.648459334781;6405.19935008124;6411.5832470889245;6424.100625912376;6134.467201660849;6259.217597800275;6262.749490020439;6272.529560282997;6331.176946996861;6263.780767673201;6285.151504546919;6328.177336946167;6329.113924050636;6353.491720662429;6273.372662733781;6317.684115794284;6331.113644089831;6336.619802811846;6344.270408902943;6223.502119830403;6302.905456418157;6344.619322840645;6360.427561519393;6372.299047104815;5007.6494645375205;5022.924656130161;5054.848354549369;5094.5160209780015;5101.056304583664;4503.572160644733;4512.616427603625;4521.208788462006;4523;4551.7724113794175;5985.880282394328;6013.278406591237;6019.428154325332;6031.758729650853;6062.030075187949;6238.812835614939;6256.436920677161;6330.746770129143;6334.018227174795;6339.11252424661;6004.939950600526;6017.976943919544;6042.39576042401;6072.787452439144;6096.0855871619615;5438.864028399243;5527.198556209327;5533.363663178786;5537.58468114893;5543.085390910454;6409.935900641027;6411.711472983727;6455.2253729553695;6455.61266324022;6469.320721324193;6289.654069026247;6303.590266632654;6305.432511074026;6319.399657032574;6324.209473815773;6376.234851817626;6417.679116044085;6460.063290822711;6461.741530338922;6478.481721462366;5274.235235890697;6236.594621349598;6292.307846137013;6340.841478963062;6342.524310676626;6323.08315294289;6328.367163283635;6330.715117819801;6331.430171284606;6340.334264902303;6151.96924015395;6204.348543402878;6252.624842509464;6257.7184026719815;6292.056191571301;5999.670018149044;6177.413145751147;6192;6226.066090086523;6238.438540531374;6080.2399700037495;6091.360407157362;6131.462717589041;6134.375358695336;6135.294441139326;5108.795648174031;6108.938910610837;6194.969025154845;6246.937530624636;6278.748850045947;6138.296437970497;6146.754129834757;6157.568970172224;6182.412670796267;6185.072239163981 04:26:26 INFO - PROCESS | 1660 | |1;ext.html;521.9947800521995;541.8401571536397;568.8919105369982;599.226997173646;608.2123649873415;32680;33299;39098.82703518895;39857;40220;44023.559764402344;44431.77784111083;44524;44627.77686111565;44718.55281447185;9631.181349585293;9848.21214302854;9853;9867.901320986806;9875.209983201326;9281.443113413183;11929.821052684216;12118.818217726697;12164.817527737046;12180.878191218064;9750.9512452438;9964.651237206699;11807.940960295187;11814.704632384173;11820.940895295511;11846.822297665542;11879.465424055938;11896.762064758743;11915.82126268098;11919.463624136935;9933.701988940344;11559.768804623944;11597.826032609517;11644.708882277926;11681.70795730105;42852.142957141;43434;43438.565614343766;43451.69644910658;43683.12633747307;3283.113559338989;3429.2112814052657;3660.4692319613773;3664.5052917856015;3983.0440694233466;29016.41967160666;30086.54870176949;30193.396132077236;30379.848100759576;30573.847130764425;2792.5950737143203;2795.65054368204;2826.1662809471254;2829.3351062500296;2840.573913912909;517684;672600.6369968167;720154;722699;724122.379388105;38153;65978.34021659818;66242.34394140185;66688.33311666918;66909;36593.26813463716;36797.63202367942;36799;36874.44688329677;36910;16115;16224.837751622568;16247.756283655755;16273.755893661604;16278.593035174215;12976.805347919788;12980.15628984113;13071.542496012595;13713.245771482465;13767.931160344135;7981.72063977758;8518.48889066658;8671.306295496344;8684.2184203422;8693.087225841322;3074.2775447769754;3601.873934412283;3602.3335682898223;3604.8377822998027;3623.05800491874;1018.9847152292573;1022.7085280694967;1045.865236218696;1052.310736467615;1074.2963358999868;27965.860170699016;28369.858150709115;28508.429831403257;28952.84188632431;29117.56323655147;1108.6507750058706;1111.3220935229504;1153.0948205658685;1189.7977343851567;1193.7135087578918;14487.565373038826;14851.554453366416;14910.850891491164;14999.925000375146;15282.38870445192;651956;660054;666580;667862;670089;65970.34029659738;66880.33119668742;67069.32930670728;67079.32920670828;67206.32793672099;1450.57933199371;1551.945681901128;1570.4895908829676;1574.1971594486895;1579.8578127968078;30442.39115217639;31088;31569.68430315759;32071.358572828878;32133.678663213068;55281.723591381786;63964;65126.023109652444;65130.02304965524;65152.67423662851;607419;609775;613735.9313203405;615551;617185;38085;44523;44534.55465445434;45215.32177017281;45838;43188.352174716776;43370.349444757725;45194;45227.54772452365;45283.7735811332;38710.612893871825;38866.80566597262;38900.221995560496;39308.65876400538;39338.2132357357;38716.70304862572;38779.418308724824;38886;39226.82319530418;39545.80227098846;31327;36288.45567316545;39773.60226397699;40328.59671403366;41868.6505079599;32807.687692492036;34872.82563587166;36322.091947700465;36873.44689829709;36951.445728313556;25737.613935791353;25786;25937.6109358356;26033.479330412905;26313.473730525664 04:26:26 INFO - PROCESS | 1660 | |2;jquery.html;413.75174895062963;415.1116610453628;418.57723699063945;418.7466582717454;426.754616095745;20359.1856325747;21030.738155710667;21037;21279.04244309006;22091.226807061757;12355.691107722289;12666.050046246546;16843.91578042111;21400.892995535;21434.142634294647;5399.217113518547;5698.145278208261;5709.314882214119;5735.569832262576;6272;6158.199434073565;6192.102145188945;6228;6228.937710622881;6236.407541283571;5863.5016023638045;6186.226721659793;6189.628622282655;6212.689365531728;6225.688715564226;5243.344581927259;5878.941790477712;6091.908621370683;6133.69331533426;6144.5084393248435;6149.2313460817395;6185.628862268279;6188;6193.87612247757;6200.976838821589;14267.00130990831;16091.517254482384;26443;26703.46593068128;26769.19692409231;2151.8924053797246;2186.6829309749996;2246.2587346175724;2309.030207312933;2315.6989591353185;10184.490775461272;10257.17942564593;10288.485575721259;11983.460744266442;14907.70184596302;1781.8930864148192;1817.8910864372722;1827.2234300422401;1834.9082545872652;1861.4229588827372;61212.466559141045;116843;121130;121985;128650.35674821566;11905.583304584297;12055.276683399024;12192.024638028937;12214.877851221374;12228.205166664138;14432.206228657524;15839.208039598205;15886.841131588537;15912.443064492687;15924.601884952737;8893;9143;9186.678466253648;9266.814663706687;9339.766505837408;9310.255179585616;9321.44071355721;9332.95333523328;9338.299627527962;9423.48170850596;4791.784369703302;4829.613630909519;4832.879178020507;4852.781624826891;4893.045856058035;2028.3509277031208;2075.7960382977835;2082.5730725201297;2090.5818836232816;2112.376848829599;900.4822227219375;996.3922007575374;1015.2334987084704;1057.4237040812623;1129.6949823547595;18448.26206951734;20076.59846803056;20742.58514829725;20863.582728345347;20925.058372372976;990.094063931509;994.5325696922434;1001.0690058245756;1026.5380578739598;1087.5921529426464;10774.030337269684;11197.272177308449;11213.271137376045;11221.887781122248;11224.382658953673;517492;517684;520749;551386;557977;130704;134736;135133;138107;139942.3002884979;1061.5488417422564;1063.1707268330715;1077.461269365333;1080.475969154959;1082.2153938394601;17695.646087078443;17851.821481785017;17912.552186194927;18304;18594;49185;54554.72722636361;55173.72413137909;56404.717976409855;56993.14510282432;76955;78306;79328;79820;81403.59298203471;8007.199280071946;8194.426390152632;8341.624626891684;8349.624266907882;8358.45670031446;12059.638210853689;12098.758024839279;12127.696807579881;12160.087993400708;12318;10313.535890884777;10565.20760942917;10616.734581635212;10624.946875265574;10631.149508039493;10236.897631023594;10332.431716255678;10514.526846291783;10688.89311106879;10725.141988640735;10501.26491145643;10607.098396636373;10643.521041552995;10865;11010;7992.320652744349;8013.6794528218215;8108.959455202922;8150.510969341622;8347.165283471604;8236.382271329554;10278.17774578047;10419.635312764018;10805.189610779065;10831.837522437012 04:26:26 INFO - PROCESS | 1660 | |3;mootools.html;1483.339913738387;1586.1672621873515;1594.8756126930512;1911.531674739689;2051.5589148333115;8295.543745094017;8925.955370223157;9083.27333813295;9091.72724818255;9095.135962083608;8749.25631321337;8978.730638080868;8996.68511602094;9015.504147271897;9030.413023153491;2544.2748816587277;2574.3049376668287;2575.8196926215173;2577.1366592191544;2578.7421257874157;2498.4378514834198;2565.4740778140435;2569.2292312306326;2575.343287461704;2581.4966081614093;2536.353229926366;2556.463142740027;2568.8972441102333;2571.459993401388;2572.3954870605394;2524.482481091372;2531.240627811658;2535.871537165962;2555.718870924197;2558.168595206547;2504.273760609421;2504.812139089576;2520.4455019895595;2530.9873450632813;2562.461883004571;8748.825023499558;9059.59231834569;9134;9141.771455713593;9144.817103657957;5522.254495643077;5672.404397538282;5672.6312789668955;5679.886402271932;5694.601377903548;3544.521489598923;3596.7122630189524;3669.3395188865993;3700.611435799256;3708.9443658345144;1046.3564907581858;1066.0991462214417;1080.573173596425;1081.988340901252;1087.8585783848127;11942.641720748392;12155.878441215651;12205.938970305091;12213.69465763363;12241.387930603434;4946.9010619787405;4994.975025124923;5018.974905125451;5078.873028174251;5116.437191908887;10604.045635892653;10610.787784244272;10611.522481488351;10613.567168432162;10615.734606634742;5741.885162296814;5744.540436765049;5744.885102297931;5749.9425005750245;5752.769889204469;4578;4629.791659375336;4768.523147685204;4772.069446457907;5190.8183213587345;4205.852795152155;4271.615554600039;4322.913541729148;4352.7823608819435;4379.759113248738;1168.7662467506364;1422.1467119728081;1438.2377340009812;1442.0338373289921;1447.073872721459;935.5930170375908;975.6487664440796;978.7650963768644;983.7147227304001;999.4453078541479;5048.873778155576;6020.819375418744;6075.392460753889;6076.149339092531;6203.658798766111;489.9779509922062;490.5094905094905;492.94084709834334;502.3318985748945;502.5251137674935;3906.902327441837;3982.7212095153063;4052.7973601320405;4113.4446849675505;4144.564820693844;10664.840027399596;11032.172587055842;11070.225084244026;11152.219344645959;11181.21731478805;9813.901860981441;11000.119990400748;11029.669109926715;11050.668479945614;11054.778904421866;7163.247858974837;8055.275025247756;8122.634481448231;8539;8650.740477785677;5864;5960.135780311917;6019.578629495893;6067.757289708362;6101.023836186189;9447.338686292169;9472.431654100501;9576.66494579255;9683.322167448492;9888.653897113567;9236.26109911192;10085.092341689284;10340.37957722539;10350.637727679496;10525.263231573716;7077.504574679723;7276.163241227327;7737.497062690907;7867.724629637935;7897;8235.341172706288;8332.750017499484;8412.957935210285;8417.70538031166;8419.873701894354;7313.817154570966;7444.143923448873;7457.81355466096;7541.7360392386;7582.734604289044;7296.124465064227;7307;7352.852942941218;7355.264473552816;7423.851522969619;7043.788686339418;7149.463790215651;7165.462590305644;7198.856022879409;7224.602646854263;4899.853004409873;4949.109160351028;4956.330895329084;5040.672356296829;5051.141305978066;5013.24801279811;5047.116754567863;5064.5441910228255;5077.695338279715;5160.716160611202 04:26:26 INFO - PROCESS | 1660 | |4;prototype.html;288.32244226068735;336.8029702623966;338.9203537168765;342.6419391735636;344.9361868054409;5514.889702205954;6250.562460627758;6597.769078082262;6657;6727.663616819158;6584.176977877765;6601.966990165042;6619.834504137399;6631.5689480183755;6659.73361065558;3931.9213615727667;4652.069586082788;4811.927821082687;4848.393950756155;4851.054044461332;4766.880827979294;4855.490173531782;4865.026994601077;4875.5368240017315;4891.364122664048;4086.3257562502154;4347.9999600092;4819.397575303087;4826.034793041388;4838.733869637169;3933.4689816874643;4818.277258411249;4831.710097394168;4835.492273311287;4898.093852637274;4037.313656678372;4076.103257283393;4178.22702799983;4196;4202.768847713374;6317.241930968267;6381.968090159566;6382.170317858695;6399.424051835311;6404.327545607691;5802.854928626776;5823.67969761663;5840.620359676607;5844.357120716718;5845.883082338372;5656.519195868356;7067.717291308341;7075.469339799535;7078.575285482836;7081.291870812877;4379.693421460499;4685.390899183119;4724.267738500538;4724.858254252378;4744.857654270378;7398.778036658909;7485.401167906609;7500.549967001997;7520.586367749772;7523.924760752378;4676.836310729176;4683.7892294846815;4692.436907571113;4694.882627934329;4695.882602934954;4757.95242047582;4764.4282686077895;4765.332853400527;4770.546798054179;4771.33201351811;5637.295338082739;5638.9436105638415;5639.689817060101;5639.718014099279;5645.181448689917;6860.416864566468;7039.9296007039275;7092.219855815856;7108.609026503592;7109.111361079865;1614.4349477682717;1625.081828766743;1638.8197298297177;1648.068841104772;1650.6286085630636;942.9198518125899;1004.9346792458613;1033.3851358441716;1035.6478797208986;1060.9787804243872;5183.844484665466;5815.563832712563;6475.899097153495;6672.199336079701;6807.319268073153;6153.661548614779;7143.678534465857;7276.017737605462;7329.523580967327;7332.4867259292405;6800.047993280945;6801.387875091166;6846.657667116624;6872.965635171892;6884.211894182864;3875.9612403876163;3903.4925459690626;3907.492026036597;3909.4526766252748;3910.9413358799643;7527.772678333089;7757.340626046736;7802.765917022499;7818.179091195457;7822.6088695564995;7540.924590754023;7605.543667379975;7615.771526854203;7621.885671714928;7630.656620452093;7006.299370063055;7739.535627862234;7776.494527855672;7786.260305271104;7793.1427542969095;7388.113426388868;7398.038255026812;7398.96300518494;7401.5189012714;7407.888881666671;6979.755708550177;7218.530795498275;7234.529755565872;7244.384227340735;7275.454340924346;7699.8845017323665;7769.92230077692;7777.300042996158;7778.961105194438;7786.338161256356;3869.438931354994;4693.037927224979;4701.247800351927;4711.693739906895;4715.339852420732;7316.122065352191;7330.157031941395;7334.596597187206;7338.486305958745;7345.632718363953;6842.828929276808;7087.7164913402885;7097.609631470319;7097.787066388017;7138.892916606152;7004.3696067354185;7011.719531218898;7044.119485064367;7071.540349877448;7087.681054352463;6965.5124141309625;6977.267386924503;6984.231734509099;6991.7902462926195;7030.015797788416;7126.786196413908;7135.892961605477;7136.215016348301;7142.64286785669;7145.82135446618;6646.468282537482;6731.259561448334;6945.479089068239;6987.056747339044;6989.8951515726285 04:26:26 INFO - PROCESS | 1660 | |5;yui.html;704.5350068954491;719.0723966083751;728.5555810955316;740.0786021403354;757.5984728094109;15295.2352382381;15491.612709682235;15498.457553985612;15503.3023513942;15512.224388780558;11542.307461552313;14560.126392416463;14561.708765824676;14575;14598.978071534997;731.2796895058364;779.4154384211841;963.3641796414367;966.5843687214496;979.794243208927;975.2246963663869;1000.3897622450299;1000.6297669862162;1001.7946321004213;1004.1113614451227;1043.572135424476;1048.7273308939693;1049.979000419991;1050.76883085721;1050.8844027157006;852.3649880838784;1036.3056751976194;1046.8639076920028;1050.6847945616323;1069.107295408335;786.3041208530434;788.0267869181556;788.3141666749907;789.0531362365185;789.7472808701218;12680.6829829255;15382.538523844303;15443.768343474856;15473.690526189526;15486.922565387102;12800.103992720513;13145.277009764459;13162.26279664437;13214;13216.405261763244;10207.693769186937;10337.9483102584;10439.582416703399;10481.161507079416;10555.997180267863;3413.8805141819917;3476.9826150869335;3572.696320812734;3595.227026189356;3615.2950174715943;81281.59359203166;82968;83143;83175;83299;9568;9605.903940960641;9648.614055437703;9768.316217864824;10413.791724165474;18834.71747923782;21317.147314107573;22347.21784737559;22366.217182398865;22370.44073898133;7479.626018699043;7565.773026809205;7639.847203056019;7654.923450765421;7673.232676732394;4087.97956010224;4201.264778663722;4232.343986682069;4283.464566929134;4314.9784251078545;3620.366435873764;3696.537932758405;3696.77819330841;3697.020289623252;3702.7222958278235;629.3894921925724;647.757091090841;648.2027106658836;655.0338251079714;670.3732010570133;538.8356551251827;614.8063360041573;622.2004723929819;624.8188025472562;641.2881701311541;7368.668409921566;7709.51505951905;8270.627821748036;8318.70884519039;8329.416940814084;593.9309243361931;602.6835911146595;613.6285402126244;619.5724949784634;631.4190944331226;3479.6868281854754;3714.7434148857405;3868.7485313454536;3917.882463526099;3926.8429262829736;87255.56372218099;87269.56365218133;89313.55343223242;90092;90631.54684226536;70358.2964170362;70656.64671676609;74275.62862185763;75324.62337688386;78979;70793.64603176952;71655;71751.56496870033;71941.2805871945;72447.63776181191;7738.806529836799;7738.8452230953935;7746.30283274508;7810;7879.172686867796;37949.43075853809;47917.76041119912;49498.25752613786;50398;51844.963100738525;66755.33244667492;66836;67102.32244193934;67715.66142169258;68428;9872.901270987486;10087.04173103554;10101.292909496266;10210.795784084425;10617.734556636145;10428.687139385831;10840.945795270973;11043.337399756041;11155.665330040112;11292.266002709797;8824.5587720615;10933.289336193122;11301.208915376174;11619.76760464769;11993.554212960227;11298.66104016848;11365.829512557153;11553.711157221136;11657.88342116602;11693.181477296508;10511;10907.509162087883;11001.119910406987;11026.669199924016;11317.773644527228;9522.61909523639;9654.082862128087;9673.22614190849;9689.70930872075;9710.126088652056;7250.492465527574;9318;9489.525523723649;9528.761780955532;9560.569774360032 04:26:26 INFO - PROCESS | 1660 | __end_tp_report 04:26:26 INFO - PROCESS | 1660 | __start_cc_report 04:26:26 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1279 04:26:26 INFO - PROCESS | 1660 | __end_cc_report 04:26:26 INFO - PROCESS | 1660 | __startTimestamp1467890786892__endTimestamp 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | ------- Summary: start ------- 04:26:26 INFO - PROCESS | 1660 | Number of tests: 6 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5860.97 Median:6134.38 stddev:636.22 (10.4%) stddev-sans-first:610.31 04:26:26 INFO - PROCESS | 1660 | Values: 3487.0 3565.9 3599.7 3626.4 3692.7 5719.1 5802.5 5828.8 5828.9 5845.1 5860.6 5866.4 5876.2 5881.9 5903.7 4306.7 5184.8 5260.4 5267.0 5375.4 5319.5 5366.2 5367.2 5372.0 5373.1 5308.1 5344.3 5344.5 5352.4 5367.8 5333.8 5385.6 5393.5 5410.1 5431.2 5165.1 5209.8 5315.3 5316.2 5338.0 5690.9 5743.9 5765.5 5772.7 5777.3 6321.0 6322.0 6344.8 6356.3 6363.6 6248.6 6255.6 6300.3 6305.7 6326.5 5943.7 5961.3 5962.4 5974.0 5975.1 6385.1 6391.6 6405.2 6411.6 6424.1 6134.5 6259.2 6262.7 6272.5 6331.2 6263.8 6285.2 6328.2 6329.1 6353.5 6273.4 6317.7 6331.1 6336.6 6344.3 6223.5 6302.9 6344.6 6360.4 6372.3 5007.6 5022.9 5054.8 5094.5 5101.1 4503.6 4512.6 4521.2 4523.0 4551.8 5985.9 6013.3 6019.4 6031.8 6062.0 6238.8 6256.4 6330.7 6334.0 6339.1 6004.9 6018.0 6042.4 6072.8 6096.1 5438.9 5527.2 5533.4 5537.6 5543.1 6409.9 6411.7 6455.2 6455.6 6469.3 6289.7 6303.6 6305.4 6319.4 6324.2 6376.2 6417.7 6460.1 6461.7 6478.5 5274.2 6236.6 6292.3 6340.8 6342.5 6323.1 6328.4 6330.7 6331.4 6340.3 6152.0 6204.3 6252.6 6257.7 6292.1 5999.7 6177.4 6192.0 6226.1 6238.4 6080.2 6091.4 6131.5 6134.4 6135.3 5108.8 6108.9 6195.0 6246.9 6278.7 6138.3 6146.8 6157.6 6182.4 6185.1 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:77170.61 Median:36873.95 stddev:174611.67 (473.5%) stddev-sans-first:175006.66 04:26:26 INFO - PROCESS | 1660 | Values: 522.0 541.8 568.9 599.2 608.2 32680.0 33299.0 39098.8 39857.0 40220.0 44023.6 44431.8 44524.0 44627.8 44718.6 9631.2 9848.2 9853.0 9867.9 9875.2 9281.4 11929.8 12118.8 12164.8 12180.9 9751.0 9964.7 11807.9 11814.7 11820.9 11846.8 11879.5 11896.8 11915.8 11919.5 9933.7 11559.8 11597.8 11644.7 11681.7 42852.1 43434.0 43438.6 43451.7 43683.1 3283.1 3429.2 3660.5 3664.5 3983.0 29016.4 30086.5 30193.4 30379.8 30573.8 2792.6 2795.7 2826.2 2829.3 2840.6 517684.0 672600.6 720154.0 722699.0 724122.4 38153.0 65978.3 66242.3 66688.3 66909.0 36593.3 36797.6 36799.0 36874.4 36910.0 16115.0 16224.8 16247.8 16273.8 16278.6 12976.8 12980.2 13071.5 13713.2 13767.9 7981.7 8518.5 8671.3 8684.2 8693.1 3074.3 3601.9 3602.3 3604.8 3623.1 1019.0 1022.7 1045.9 1052.3 1074.3 27965.9 28369.9 28508.4 28952.8 29117.6 1108.7 1111.3 1153.1 1189.8 1193.7 14487.6 14851.6 14910.9 14999.9 15282.4 651956.0 660054.0 666580.0 667862.0 670089.0 65970.3 66880.3 67069.3 67079.3 67206.3 1450.6 1551.9 1570.5 1574.2 1579.9 30442.4 31088.0 31569.7 32071.4 32133.7 55281.7 63964.0 65126.0 65130.0 65152.7 607419.0 609775.0 613735.9 615551.0 617185.0 38085.0 44523.0 44534.6 45215.3 45838.0 43188.4 43370.3 45194.0 45227.5 45283.8 38710.6 38866.8 38900.2 39308.7 39338.2 38716.7 38779.4 38886.0 39226.8 39545.8 31327.0 36288.5 39773.6 40328.6 41868.7 32807.7 34872.8 36322.1 36873.4 36951.4 25737.6 25786.0 25937.6 26033.5 26313.5 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:33189.12 Median:11574.69 stddev:89841.36 (776.2%) stddev-sans-first:90059.68 04:26:26 INFO - PROCESS | 1660 | Values: 413.8 415.1 418.6 418.7 426.8 20359.2 21030.7 21037.0 21279.0 22091.2 12355.7 12666.1 16843.9 21400.9 21434.1 5399.2 5698.1 5709.3 5735.6 6272.0 6158.2 6192.1 6228.0 6228.9 6236.4 5863.5 6186.2 6189.6 6212.7 6225.7 5243.3 5878.9 6091.9 6133.7 6144.5 6149.2 6185.6 6188.0 6193.9 6201.0 14267.0 16091.5 26443.0 26703.5 26769.2 2151.9 2186.7 2246.3 2309.0 2315.7 10184.5 10257.2 10288.5 11983.5 14907.7 1781.9 1817.9 1827.2 1834.9 1861.4 61212.5 116843.0 121130.0 121985.0 128650.4 11905.6 12055.3 12192.0 12214.9 12228.2 14432.2 15839.2 15886.8 15912.4 15924.6 8893.0 9143.0 9186.7 9266.8 9339.8 9310.3 9321.4 9333.0 9338.3 9423.5 4791.8 4829.6 4832.9 4852.8 4893.0 2028.4 2075.8 2082.6 2090.6 2112.4 900.5 996.4 1015.2 1057.4 1129.7 18448.3 20076.6 20742.6 20863.6 20925.1 990.1 994.5 1001.1 1026.5 1087.6 10774.0 11197.3 11213.3 11221.9 11224.4 517492.0 517684.0 520749.0 551386.0 557977.0 130704.0 134736.0 135133.0 138107.0 139942.3 1061.5 1063.2 1077.5 1080.5 1082.2 17695.6 17851.8 17912.6 18304.0 18594.0 49185.0 54554.7 55173.7 56404.7 56993.1 76955.0 78306.0 79328.0 79820.0 81403.6 8007.2 8194.4 8341.6 8349.6 8358.5 12059.6 12098.8 12127.7 12160.1 12318.0 10313.5 10565.2 10616.7 10624.9 10631.1 10236.9 10332.4 10514.5 10688.9 10725.1 10501.3 10607.1 10643.5 10865.0 11010.0 7992.3 8013.7 8109.0 8150.5 8347.2 8236.4 10278.2 10419.6 10805.2 10831.8 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5854.34 Median:5016.11 stddev:3247.96 (64.8%) stddev-sans-first:3240.46 04:26:26 INFO - PROCESS | 1660 | Values: 1483.3 1586.2 1594.9 1911.5 2051.6 8295.5 8926.0 9083.3 9091.7 9095.1 8749.3 8978.7 8996.7 9015.5 9030.4 2544.3 2574.3 2575.8 2577.1 2578.7 2498.4 2565.5 2569.2 2575.3 2581.5 2536.4 2556.5 2568.9 2571.5 2572.4 2524.5 2531.2 2535.9 2555.7 2558.2 2504.3 2504.8 2520.4 2531.0 2562.5 8748.8 9059.6 9134.0 9141.8 9144.8 5522.3 5672.4 5672.6 5679.9 5694.6 3544.5 3596.7 3669.3 3700.6 3708.9 1046.4 1066.1 1080.6 1082.0 1087.9 11942.6 12155.9 12205.9 12213.7 12241.4 4946.9 4995.0 5019.0 5078.9 5116.4 10604.0 10610.8 10611.5 10613.6 10615.7 5741.9 5744.5 5744.9 5749.9 5752.8 4578.0 4629.8 4768.5 4772.1 5190.8 4205.9 4271.6 4322.9 4352.8 4379.8 1168.8 1422.1 1438.2 1442.0 1447.1 935.6 975.6 978.8 983.7 999.4 5048.9 6020.8 6075.4 6076.1 6203.7 490.0 490.5 492.9 502.3 502.5 3906.9 3982.7 4052.8 4113.4 4144.6 10664.8 11032.2 11070.2 11152.2 11181.2 9813.9 11000.1 11029.7 11050.7 11054.8 7163.2 8055.3 8122.6 8539.0 8650.7 5864.0 5960.1 6019.6 6067.8 6101.0 9447.3 9472.4 9576.7 9683.3 9888.7 9236.3 10085.1 10340.4 10350.6 10525.3 7077.5 7276.2 7737.5 7867.7 7897.0 8235.3 8332.8 8413.0 8417.7 8419.9 7313.8 7444.1 7457.8 7541.7 7582.7 7296.1 7307.0 7352.9 7355.3 7423.9 7043.8 7149.5 7165.5 7198.9 7224.6 4899.9 4949.1 4956.3 5040.7 5051.1 5013.2 5047.1 5064.5 5077.7 5160.7 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:5787.15 Median:6639.02 stddev:1880.01 (28.3%) stddev-sans-first:1839.43 04:26:26 INFO - PROCESS | 1660 | Values: 288.3 336.8 338.9 342.6 344.9 5514.9 6250.6 6597.8 6657.0 6727.7 6584.2 6602.0 6619.8 6631.6 6659.7 3931.9 4652.1 4811.9 4848.4 4851.1 4766.9 4855.5 4865.0 4875.5 4891.4 4086.3 4348.0 4819.4 4826.0 4838.7 3933.5 4818.3 4831.7 4835.5 4898.1 4037.3 4076.1 4178.2 4196.0 4202.8 6317.2 6382.0 6382.2 6399.4 6404.3 5802.9 5823.7 5840.6 5844.4 5845.9 5656.5 7067.7 7075.5 7078.6 7081.3 4379.7 4685.4 4724.3 4724.9 4744.9 7398.8 7485.4 7500.5 7520.6 7523.9 4676.8 4683.8 4692.4 4694.9 4695.9 4758.0 4764.4 4765.3 4770.5 4771.3 5637.3 5638.9 5639.7 5639.7 5645.2 6860.4 7039.9 7092.2 7108.6 7109.1 1614.4 1625.1 1638.8 1648.1 1650.6 942.9 1004.9 1033.4 1035.6 1061.0 5183.8 5815.6 6475.9 6672.2 6807.3 6153.7 7143.7 7276.0 7329.5 7332.5 6800.0 6801.4 6846.7 6873.0 6884.2 3876.0 3903.5 3907.5 3909.5 3910.9 7527.8 7757.3 7802.8 7818.2 7822.6 7540.9 7605.5 7615.8 7621.9 7630.7 7006.3 7739.5 7776.5 7786.3 7793.1 7388.1 7398.0 7399.0 7401.5 7407.9 6979.8 7218.5 7234.5 7244.4 7275.5 7699.9 7769.9 7777.3 7779.0 7786.3 3869.4 4693.0 4701.2 4711.7 4715.3 7316.1 7330.2 7334.6 7338.5 7345.6 6842.8 7087.7 7097.6 7097.8 7138.9 7004.4 7011.7 7044.1 7071.5 7087.7 6965.5 6977.3 6984.2 6991.8 7030.0 7126.8 7135.9 7136.2 7142.6 7145.8 6646.5 6731.3 6945.5 6987.1 6989.9 04:26:26 INFO - PROCESS | 1660 | 04:26:26 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:18067.80 Median:51121.48 stddev:25326.48 (49.5%) stddev-sans-first:25363.97 04:26:26 INFO - PROCESS | 1660 | Values: 704.5 719.1 728.6 740.1 757.6 15295.2 15491.6 15498.5 15503.3 15512.2 11542.3 14560.1 14561.7 14575.0 14599.0 731.3 779.4 963.4 966.6 979.8 975.2 1000.4 1000.6 1001.8 1004.1 1043.6 1048.7 1050.0 1050.8 1050.9 852.4 1036.3 1046.9 1050.7 1069.1 786.3 788.0 788.3 789.1 789.7 12680.7 15382.5 15443.8 15473.7 15486.9 12800.1 13145.3 13162.3 13214.0 13216.4 10207.7 10337.9 10439.6 10481.2 10556.0 3413.9 3477.0 3572.7 3595.2 3615.3 81281.6 82968.0 83143.0 83175.0 83299.0 9568.0 9605.9 9648.6 9768.3 10413.8 18834.7 21317.1 22347.2 22366.2 22370.4 7479.6 7565.8 7639.8 7654.9 7673.2 4088.0 4201.3 4232.3 4283.5 4315.0 3620.4 3696.5 3696.8 3697.0 3702.7 629.4 647.8 648.2 655.0 670.4 538.8 614.8 622.2 624.8 641.3 7368.7 7709.5 8270.6 8318.7 8329.4 593.9 602.7 613.6 619.6 631.4 3479.7 3714.7 3868.7 3917.9 3926.8 87255.6 87269.6 89313.6 90092.0 90631.5 70358.3 70656.6 74275.6 75324.6 78979.0 70793.6 71655.0 71751.6 71941.3 72447.6 7738.8 7738.8 7746.3 7810.0 7879.2 37949.4 47917.8 49498.3 50398.0 51845.0 66755.3 66836.0 67102.3 67715.7 68428.0 9872.9 10087.0 10101.3 10210.8 10617.7 10428.7 10840.9 11043.3 11155.7 11292.3 8824.6 10933.3 11301.2 11619.8 11993.6 11298.7 11365.8 11553.7 11657.9 11693.2 10511.0 10907.5 11001.1 11026.7 11317.8 9522.6 9654.1 9673.2 9689.7 9710.1 7250.5 9318.0 9489.5 9528.8 9560.6 04:26:26 INFO - PROCESS | 1660 | -------- Summary: end -------- 04:26:26 INFO - PROCESS | 1660 | 04:26:27 INFO - TEST-INFO | 1660: exit 0 04:26:27 INFO - TEST-OK | dromaeo_css | took 1112955ms 04:26:27 INFO - TEST-START | kraken 04:26:27 INFO - operating with platform_type : mac_ 04:26:27 INFO - Initialising browser for kraken test... 04:26:27 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmp2Ok__x/profile http://localhost:49279/getInfo.html) 04:26:29 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 04:26:29 INFO - PROCESS | 1670 | colorDepth:24 04:26:29 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 04:26:29 INFO - PROCESS | 1670 | __metrics 04:26:29 INFO - PROCESS | 1670 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:26:29 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 04:26:30 INFO - TEST-INFO | 1670: exit 0 04:26:30 INFO - Browser initialized. 04:26:30 INFO - Running cycle 1/1 for kraken test... 04:26:30 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/tmp2Ok__x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:26:42 INFO - PROCESS | 1673 | RSS: Main: 161837056 04:26:42 INFO - PROCESS | 1673 | 04:26:44 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 04:26:45 INFO - PROCESS | 1673 | RSS: Main: 223817728 04:26:45 INFO - PROCESS | 1673 | 04:26:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 04:26:48 INFO - PROCESS | 1673 | RSS: Main: 239702016 04:26:48 INFO - PROCESS | 1673 | 04:26:52 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 04:26:52 INFO - PROCESS | 1673 | RSS: Main: 325341184 04:26:52 INFO - PROCESS | 1673 | 04:26:54 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 04:26:55 INFO - PROCESS | 1673 | RSS: Main: 231415808 04:26:55 INFO - PROCESS | 1673 | 04:26:57 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:26:57 INFO - PROCESS | 1673 | RSS: Main: 231747584 04:26:57 INFO - PROCESS | 1673 | 04:27:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 04:27:01 INFO - PROCESS | 1673 | RSS: Main: 268541952 04:27:01 INFO - PROCESS | 1673 | 04:27:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 04:27:04 INFO - PROCESS | 1673 | RSS: Main: 271003648 04:27:04 INFO - PROCESS | 1673 | 04:27:07 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 04:27:07 INFO - PROCESS | 1673 | RSS: Main: 268795904 04:27:07 INFO - PROCESS | 1673 | 04:27:09 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:27:10 INFO - PROCESS | 1673 | RSS: Main: 260579328 04:27:10 INFO - PROCESS | 1673 | 04:27:11 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:27:12 INFO - PROCESS | 1673 | RSS: Main: 267567104 04:27:12 INFO - PROCESS | 1673 | 04:27:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:27:15 INFO - PROCESS | 1673 | RSS: Main: 271077376 04:27:15 INFO - PROCESS | 1673 | 04:27:18 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:27:18 INFO - PROCESS | 1673 | RSS: Main: 278175744 04:27:18 INFO - PROCESS | 1673 | 04:27:21 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:27:22 INFO - PROCESS | 1673 | RSS: Main: 275443712 04:27:22 INFO - PROCESS | 1673 | 04:27:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 04:27:24 INFO - PROCESS | 1673 | RSS: Main: 336015360 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | __start_tp_report 04:27:24 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 04:27:24 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 04:27:24 INFO - PROCESS | 1673 | |i|pagename|runs| 04:27:24 INFO - PROCESS | 1673 | |0;ai-astar;113;111;119;111;112;111;113;113;113;135 04:27:24 INFO - PROCESS | 1673 | |1;audio-beat-detection;162;151;183;163;118;138;117;153;153;112 04:27:24 INFO - PROCESS | 1673 | |2;audio-dft;187;184;192;185;183;191;256;186;184;184 04:27:24 INFO - PROCESS | 1673 | |3;audio-fft;70;78;77;157;54;56;55;61;137;55 04:27:24 INFO - PROCESS | 1673 | |4;audio-oscillator;92;92;140;79;88;87;121;77;80;127 04:27:24 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;88;86;88;85;88;84;90;86;88;84 04:27:24 INFO - PROCESS | 1673 | |6;imaging-darkroom;94;93;93;93;93;94;93;93;92;93 04:27:24 INFO - PROCESS | 1673 | |7;imaging-desaturate;85;86;85;84;84;83;84;85;85;85 04:27:24 INFO - PROCESS | 1673 | |8;json-parse-financial;59;62;60;61;63;63;63;78;57;56 04:27:24 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;62;65;72;75;75;69;67;63;65;65 04:27:24 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;79;80;80;79;80;79;79;79;79;84 04:27:24 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;127;129;134;141;144;124;124;123;172;162 04:27:24 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;187;170;163;162;163;163;160;161;166;160 04:27:24 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;68;64;65;68;65;66;66;66;67;66 04:27:24 INFO - PROCESS | 1673 | __end_tp_report 04:27:24 INFO - PROCESS | 1673 | __start_cc_report 04:27:24 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1912 04:27:24 INFO - PROCESS | 1673 | __end_cc_report 04:27:24 INFO - PROCESS | 1673 | __startTimestamp1467890844052__endTimestamp 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | ------- Summary: start ------- 04:27:24 INFO - PROCESS | 1673 | Number of tests: 14 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:115.10 Median:113.00 stddev:7.37 (6.5%) stddev-sans-first:7.78 04:27:24 INFO - PROCESS | 1673 | Values: 113.0 111.0 119.0 111.0 112.0 111.0 113.0 113.0 113.0 135.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:145.00 Median:153.00 stddev:23.27 (15.2%) stddev-sans-first:23.85 04:27:24 INFO - PROCESS | 1673 | Values: 162.0 151.0 183.0 163.0 118.0 138.0 117.0 153.0 153.0 112.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:193.20 Median:186.50 stddev:22.28 (11.9%) stddev-sans-first:23.51 04:27:24 INFO - PROCESS | 1673 | Values: 187.0 184.0 192.0 185.0 183.0 191.0 256.0 186.0 184.0 184.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:80.00 Median:58.50 stddev:36.75 (62.8%) stddev-sans-first:38.80 04:27:24 INFO - PROCESS | 1673 | Values: 70.0 78.0 77.0 157.0 54.0 56.0 55.0 61.0 137.0 55.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:98.30 Median:83.50 stddev:22.49 (26.9%) stddev-sans-first:23.74 04:27:24 INFO - PROCESS | 1673 | Values: 92.0 92.0 140.0 79.0 88.0 87.0 121.0 77.0 80.0 127.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:86.70 Median:88.00 stddev:2.00 (2.3%) stddev-sans-first:2.07 04:27:24 INFO - PROCESS | 1673 | Values: 88.0 86.0 88.0 85.0 88.0 84.0 90.0 86.0 88.0 84.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:93.10 Median:93.00 stddev:0.57 (0.6%) stddev-sans-first:0.50 04:27:24 INFO - PROCESS | 1673 | Values: 94.0 93.0 93.0 93.0 93.0 94.0 93.0 93.0 92.0 93.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:85.00 stddev:0.84 (1.0%) stddev-sans-first:0.88 04:27:24 INFO - PROCESS | 1673 | Values: 85.0 86.0 85.0 84.0 84.0 83.0 84.0 85.0 85.0 85.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:62.20 Median:62.50 stddev:6.09 (9.7%) stddev-sans-first:6.35 04:27:24 INFO - PROCESS | 1673 | Values: 59.0 62.0 60.0 61.0 63.0 63.0 63.0 78.0 57.0 56.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:67.80 Median:68.00 stddev:4.76 (7.0%) stddev-sans-first:4.56 04:27:24 INFO - PROCESS | 1673 | Values: 62.0 65.0 72.0 75.0 75.0 69.0 67.0 63.0 65.0 65.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:79.80 Median:79.50 stddev:1.55 (1.9%) stddev-sans-first:1.62 04:27:24 INFO - PROCESS | 1673 | Values: 79.0 80.0 80.0 79.0 80.0 79.0 79.0 79.0 79.0 84.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:138.00 Median:137.50 stddev:17.04 (12.4%) stddev-sans-first:17.60 04:27:24 INFO - PROCESS | 1673 | Values: 127.0 129.0 134.0 141.0 144.0 124.0 124.0 123.0 172.0 162.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.50 Median:163.00 stddev:8.13 (5.0%) stddev-sans-first:3.18 04:27:24 INFO - PROCESS | 1673 | Values: 187.0 170.0 163.0 162.0 163.0 163.0 160.0 161.0 166.0 160.0 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.10 Median:66.00 stddev:1.29 (1.9%) stddev-sans-first:1.17 04:27:24 INFO - PROCESS | 1673 | Values: 68.0 64.0 65.0 68.0 65.0 66.0 66.0 66.0 67.0 66.0 04:27:24 INFO - PROCESS | 1673 | -------- Summary: end -------- 04:27:24 INFO - PROCESS | 1673 | 04:27:24 INFO - TEST-INFO | 1673: exit 0 04:27:24 INFO - TEST-OK | kraken | took 57361ms 04:27:24 INFO - SUITE-END | took 1170s 04:27:25 INFO - Completed test suite (00:19:30) 04:27:25 INFO - javascript benchmark 04:27:25 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3486.9999999999995, 3565.857365705372, 3599.712023038157, 3626.4197728363465, 3692.7415080944343, 5719.056355701309, 5802.477777000069, 5828.795992140276, 5828.854278643036, 5845.094010428385, 5860.619059761112, 5866.413358664131, 5876.177335173079, 5881.911771323434, 5903.675297858626, 4306.720063195898, 5184.818531351403, 5260.368755749316, 5267.0, 5375.43557926416, 5319.521243088121, 5366.248725178478, 5367.1680889462195, 5371.973140134294, 5373.113436283009, 5308.071087559681, 5344.331958505187, 5344.4922732340365, 5352.437994010631, 5367.758450869701, 5333.786648534074, 5385.649932754359, 5393.460653934575, 5410.08028635133, 5431.158170483582, 5165.1219292720325, 5209.765560549784, 5315.255864179018, 5316.20256961455, 5338.0, 5690.943090569082, 5743.913841292384, 5765.538756899438, 5772.711364431803, 5777.277840269966, 6321.0, 6322.0, 6344.809655710336, 6356.269029061671, 6363.61818290904, 6248.625082495065, 6255.624662520263, 6300.338464461257, 6305.716242769086, 6326.5255105867245, 5943.692899264243, 5961.2846458425265, 5962.37395073511, 5974.0, 5975.073863551156, 6385.13800636908, 6391.648459334781, 6405.19935008124, 6411.5832470889245, 6424.100625912376, 6134.467201660849, 6259.217597800275, 6262.749490020439, 6272.529560282997, 6331.176946996861, 6263.780767673201, 6285.151504546919, 6328.177336946167, 6329.113924050636, 6353.491720662429, 6273.372662733781, 6317.684115794284, 6331.113644089831, 6336.619802811846, 6344.270408902943, 6223.502119830403, 6302.905456418157, 6344.619322840645, 6360.427561519393, 6372.299047104815, 5007.6494645375205, 5022.924656130161, 5054.848354549369, 5094.5160209780015, 5101.056304583664, 4503.572160644733, 4512.616427603625, 4521.208788462006, 4523.0, 4551.7724113794175, 5985.880282394328, 6013.278406591237, 6019.428154325332, 6031.758729650853, 6062.030075187949, 6238.812835614939, 6256.436920677161, 6330.746770129143, 6334.018227174795, 6339.11252424661, 6004.939950600526, 6017.976943919544, 6042.39576042401, 6072.787452439144, 6096.0855871619615, 5438.864028399243, 5527.198556209327, 5533.363663178786, 5537.58468114893, 5543.085390910454, 6409.935900641027, 6411.711472983727, 6455.2253729553695, 6455.61266324022, 6469.320721324193, 6289.654069026247, 6303.590266632654, 6305.432511074026, 6319.399657032574, 6324.209473815773, 6376.234851817626, 6417.679116044085, 6460.063290822711, 6461.741530338922, 6478.481721462366, 5274.235235890697, 6236.594621349598, 6292.307846137013, 6340.841478963062, 6342.524310676626, 6323.08315294289, 6328.367163283635, 6330.715117819801, 6331.430171284606, 6340.334264902303, 6151.96924015395, 6204.348543402878, 6252.624842509464, 6257.7184026719815, 6292.056191571301, 5999.670018149044, 6177.413145751147, 6192.0, 6226.066090086523, 6238.438540531374, 6080.2399700037495, 6091.360407157362, 6131.462717589041, 6134.375358695336, 6135.294441139326, 5108.795648174031, 6108.938910610837, 6194.969025154845, 6246.937530624636, 6278.748850045947, 6138.296437970497, 6146.754129834757, 6157.568970172224, 6182.412670796267, 6185.072239163981], "value": 5822.408545693004, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [521.9947800521995, 541.8401571536397, 568.8919105369982, 599.226997173646, 608.2123649873415, 32680.0, 33299.0, 39098.82703518895, 39857.0, 40220.0, 44023.559764402344, 44431.77784111083, 44524.0, 44627.77686111565, 44718.55281447185, 9631.181349585293, 9848.21214302854, 9853.0, 9867.901320986806, 9875.209983201326, 9281.443113413183, 11929.821052684216, 12118.818217726697, 12164.817527737046, 12180.878191218064, 9750.9512452438, 9964.651237206699, 11807.940960295187, 11814.704632384173, 11820.940895295511, 11846.822297665542, 11879.465424055938, 11896.762064758743, 11915.82126268098, 11919.463624136935, 9933.701988940344, 11559.768804623944, 11597.826032609517, 11644.708882277926, 11681.70795730105, 42852.142957141, 43434.0, 43438.565614343766, 43451.69644910658, 43683.12633747307, 3283.113559338989, 3429.2112814052657, 3660.4692319613773, 3664.5052917856015, 3983.0440694233466, 29016.41967160666, 30086.54870176949, 30193.396132077236, 30379.848100759576, 30573.847130764425, 2792.5950737143203, 2795.65054368204, 2826.1662809471254, 2829.3351062500296, 2840.573913912909, 517684.0, 672600.6369968167, 720154.0, 722699.0, 724122.379388105, 38153.0, 65978.34021659818, 66242.34394140185, 66688.33311666918, 66909.0, 36593.26813463716, 36797.63202367942, 36799.0, 36874.44688329677, 36910.0, 16115.0, 16224.837751622568, 16247.756283655755, 16273.755893661604, 16278.593035174215, 12976.805347919788, 12980.15628984113, 13071.542496012595, 13713.245771482465, 13767.931160344135, 7981.72063977758, 8518.48889066658, 8671.306295496344, 8684.2184203422, 8693.087225841322, 3074.2775447769754, 3601.873934412283, 3602.3335682898223, 3604.8377822998027, 3623.05800491874, 1018.9847152292573, 1022.7085280694967, 1045.865236218696, 1052.310736467615, 1074.2963358999868, 27965.860170699016, 28369.858150709115, 28508.429831403257, 28952.84188632431, 29117.56323655147, 1108.6507750058706, 1111.3220935229504, 1153.0948205658685, 1189.7977343851567, 1193.7135087578918, 14487.565373038826, 14851.554453366416, 14910.850891491164, 14999.925000375146, 15282.38870445192, 651956.0, 660054.0, 666580.0, 667862.0, 670089.0, 65970.34029659738, 66880.33119668742, 67069.32930670728, 67079.32920670828, 67206.32793672099, 1450.57933199371, 1551.945681901128, 1570.4895908829676, 1574.1971594486895, 1579.8578127968078, 30442.39115217639, 31088.0, 31569.68430315759, 32071.358572828878, 32133.678663213068, 55281.723591381786, 63964.0, 65126.023109652444, 65130.02304965524, 65152.67423662851, 607419.0, 609775.0, 613735.9313203405, 615551.0, 617185.0, 38085.0, 44523.0, 44534.55465445434, 45215.32177017281, 45838.0, 43188.352174716776, 43370.349444757725, 45194.0, 45227.54772452365, 45283.7735811332, 38710.612893871825, 38866.80566597262, 38900.221995560496, 39308.65876400538, 39338.2132357357, 38716.70304862572, 38779.418308724824, 38886.0, 39226.82319530418, 39545.80227098846, 31327.0, 36288.45567316545, 39773.60226397699, 40328.59671403366, 41868.6505079599, 32807.687692492036, 34872.82563587166, 36322.091947700465, 36873.44689829709, 36951.445728313556, 25737.613935791353, 25786.0, 25937.6109358356, 26033.479330412905, 26313.473730525664], "value": 20141.271176137743, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [413.75174895062963, 415.1116610453628, 418.57723699063945, 418.7466582717454, 426.754616095745, 20359.1856325747, 21030.738155710667, 21037.0, 21279.04244309006, 22091.226807061757, 12355.691107722289, 12666.050046246546, 16843.91578042111, 21400.892995535, 21434.142634294647, 5399.217113518547, 5698.145278208261, 5709.314882214119, 5735.569832262576, 6272.0, 6158.199434073565, 6192.102145188945, 6228.0, 6228.937710622881, 6236.407541283571, 5863.5016023638045, 6186.226721659793, 6189.628622282655, 6212.689365531728, 6225.688715564226, 5243.344581927259, 5878.941790477712, 6091.908621370683, 6133.69331533426, 6144.5084393248435, 6149.2313460817395, 6185.628862268279, 6188.0, 6193.87612247757, 6200.976838821589, 14267.00130990831, 16091.517254482384, 26443.0, 26703.46593068128, 26769.19692409231, 2151.8924053797246, 2186.6829309749996, 2246.2587346175724, 2309.030207312933, 2315.6989591353185, 10184.490775461272, 10257.17942564593, 10288.485575721259, 11983.460744266442, 14907.70184596302, 1781.8930864148192, 1817.8910864372722, 1827.2234300422401, 1834.9082545872652, 1861.4229588827372, 61212.466559141045, 116843.0, 121130.0, 121985.0, 128650.35674821566, 11905.583304584297, 12055.276683399024, 12192.024638028937, 12214.877851221374, 12228.205166664138, 14432.206228657524, 15839.208039598205, 15886.841131588537, 15912.443064492687, 15924.601884952737, 8893.0, 9143.0, 9186.678466253648, 9266.814663706687, 9339.766505837408, 9310.255179585616, 9321.44071355721, 9332.95333523328, 9338.299627527962, 9423.48170850596, 4791.784369703302, 4829.613630909519, 4832.879178020507, 4852.781624826891, 4893.045856058035, 2028.3509277031208, 2075.7960382977835, 2082.5730725201297, 2090.5818836232816, 2112.376848829599, 900.4822227219375, 996.3922007575374, 1015.2334987084704, 1057.4237040812623, 1129.6949823547595, 18448.26206951734, 20076.59846803056, 20742.58514829725, 20863.582728345347, 20925.058372372976, 990.094063931509, 994.5325696922434, 1001.0690058245756, 1026.5380578739598, 1087.5921529426464, 10774.030337269684, 11197.272177308449, 11213.271137376045, 11221.887781122248, 11224.382658953673, 517492.0, 517684.0, 520749.0, 551386.0, 557977.0, 130704.0, 134736.0, 135133.0, 138107.0, 139942.3002884979, 1061.5488417422564, 1063.1707268330715, 1077.461269365333, 1080.475969154959, 1082.2153938394601, 17695.646087078443, 17851.821481785017, 17912.552186194927, 18304.0, 18594.0, 49185.0, 54554.72722636361, 55173.72413137909, 56404.717976409855, 56993.14510282432, 76955.0, 78306.0, 79328.0, 79820.0, 81403.59298203471, 8007.199280071946, 8194.426390152632, 8341.624626891684, 8349.624266907882, 8358.45670031446, 12059.638210853689, 12098.758024839279, 12127.696807579881, 12160.087993400708, 12318.0, 10313.535890884777, 10565.20760942917, 10616.734581635212, 10624.946875265574, 10631.149508039493, 10236.897631023594, 10332.431716255678, 10514.526846291783, 10688.89311106879, 10725.141988640735, 10501.26491145643, 10607.098396636373, 10643.521041552995, 10865.0, 11010.0, 7992.320652744349, 8013.6794528218215, 8108.959455202922, 8150.510969341622, 8347.165283471604, 8236.382271329554, 10278.17774578047, 10419.635312764018, 10805.189610779065, 10831.837522437012], "value": 9681.18105753444, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1483.339913738387, 1586.1672621873515, 1594.8756126930512, 1911.531674739689, 2051.5589148333115, 8295.543745094017, 8925.955370223157, 9083.27333813295, 9091.72724818255, 9095.135962083608, 8749.25631321337, 8978.730638080868, 8996.68511602094, 9015.504147271897, 9030.413023153491, 2544.2748816587277, 2574.3049376668287, 2575.8196926215173, 2577.1366592191544, 2578.7421257874157, 2498.4378514834198, 2565.4740778140435, 2569.2292312306326, 2575.343287461704, 2581.4966081614093, 2536.353229926366, 2556.463142740027, 2568.8972441102333, 2571.459993401388, 2572.3954870605394, 2524.482481091372, 2531.240627811658, 2535.871537165962, 2555.718870924197, 2558.168595206547, 2504.273760609421, 2504.812139089576, 2520.4455019895595, 2530.9873450632813, 2562.461883004571, 8748.825023499558, 9059.59231834569, 9134.0, 9141.771455713593, 9144.817103657957, 5522.254495643077, 5672.404397538282, 5672.6312789668955, 5679.886402271932, 5694.601377903548, 3544.521489598923, 3596.7122630189524, 3669.3395188865993, 3700.611435799256, 3708.9443658345144, 1046.3564907581858, 1066.0991462214417, 1080.573173596425, 1081.988340901252, 1087.8585783848127, 11942.641720748392, 12155.878441215651, 12205.938970305091, 12213.69465763363, 12241.387930603434, 4946.9010619787405, 4994.975025124923, 5018.974905125451, 5078.873028174251, 5116.437191908887, 10604.045635892653, 10610.787784244272, 10611.522481488351, 10613.567168432162, 10615.734606634742, 5741.885162296814, 5744.540436765049, 5744.885102297931, 5749.9425005750245, 5752.769889204469, 4578.0, 4629.791659375336, 4768.523147685204, 4772.069446457907, 5190.8183213587345, 4205.852795152155, 4271.615554600039, 4322.913541729148, 4352.7823608819435, 4379.759113248738, 1168.7662467506364, 1422.1467119728081, 1438.2377340009812, 1442.0338373289921, 1447.073872721459, 935.5930170375908, 975.6487664440796, 978.7650963768644, 983.7147227304001, 999.4453078541479, 5048.873778155576, 6020.819375418744, 6075.392460753889, 6076.149339092531, 6203.658798766111, 489.9779509922062, 490.5094905094905, 492.94084709834334, 502.3318985748945, 502.5251137674935, 3906.902327441837, 3982.7212095153063, 4052.7973601320405, 4113.4446849675505, 4144.564820693844, 10664.840027399596, 11032.172587055842, 11070.225084244026, 11152.219344645959, 11181.21731478805, 9813.901860981441, 11000.119990400748, 11029.669109926715, 11050.668479945614, 11054.778904421866, 7163.247858974837, 8055.275025247756, 8122.634481448231, 8539.0, 8650.740477785677, 5864.0, 5960.135780311917, 6019.578629495893, 6067.757289708362, 6101.023836186189, 9447.338686292169, 9472.431654100501, 9576.66494579255, 9683.322167448492, 9888.653897113567, 9236.26109911192, 10085.092341689284, 10340.37957722539, 10350.637727679496, 10525.263231573716, 7077.504574679723, 7276.163241227327, 7737.497062690907, 7867.724629637935, 7897.0, 8235.341172706288, 8332.750017499484, 8412.957935210285, 8417.70538031166, 8419.873701894354, 7313.817154570966, 7444.143923448873, 7457.81355466096, 7541.7360392386, 7582.734604289044, 7296.124465064227, 7307.0, 7352.852942941218, 7355.264473552816, 7423.851522969619, 7043.788686339418, 7149.463790215651, 7165.462590305644, 7198.856022879409, 7224.602646854263, 4899.853004409873, 4949.109160351028, 4956.330895329084, 5040.672356296829, 5051.141305978066, 5013.24801279811, 5047.116754567863, 5064.5441910228255, 5077.695338279715, 5160.716160611202], "value": 4673.898260222542, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.32244226068735, 336.8029702623966, 338.9203537168765, 342.6419391735636, 344.9361868054409, 5514.889702205954, 6250.562460627758, 6597.769078082262, 6657.0, 6727.663616819158, 6584.176977877765, 6601.966990165042, 6619.834504137399, 6631.5689480183755, 6659.73361065558, 3931.9213615727667, 4652.069586082788, 4811.927821082687, 4848.393950756155, 4851.054044461332, 4766.880827979294, 4855.490173531782, 4865.026994601077, 4875.5368240017315, 4891.364122664048, 4086.3257562502154, 4347.9999600092, 4819.397575303087, 4826.034793041388, 4838.733869637169, 3933.4689816874643, 4818.277258411249, 4831.710097394168, 4835.492273311287, 4898.093852637274, 4037.313656678372, 4076.103257283393, 4178.22702799983, 4196.0, 4202.768847713374, 6317.241930968267, 6381.968090159566, 6382.170317858695, 6399.424051835311, 6404.327545607691, 5802.854928626776, 5823.67969761663, 5840.620359676607, 5844.357120716718, 5845.883082338372, 5656.519195868356, 7067.717291308341, 7075.469339799535, 7078.575285482836, 7081.291870812877, 4379.693421460499, 4685.390899183119, 4724.267738500538, 4724.858254252378, 4744.857654270378, 7398.778036658909, 7485.401167906609, 7500.549967001997, 7520.586367749772, 7523.924760752378, 4676.836310729176, 4683.7892294846815, 4692.436907571113, 4694.882627934329, 4695.882602934954, 4757.95242047582, 4764.4282686077895, 4765.332853400527, 4770.546798054179, 4771.33201351811, 5637.295338082739, 5638.9436105638415, 5639.689817060101, 5639.718014099279, 5645.181448689917, 6860.416864566468, 7039.9296007039275, 7092.219855815856, 7108.609026503592, 7109.111361079865, 1614.4349477682717, 1625.081828766743, 1638.8197298297177, 1648.068841104772, 1650.6286085630636, 942.9198518125899, 1004.9346792458613, 1033.3851358441716, 1035.6478797208986, 1060.9787804243872, 5183.844484665466, 5815.563832712563, 6475.899097153495, 6672.199336079701, 6807.319268073153, 6153.661548614779, 7143.678534465857, 7276.017737605462, 7329.523580967327, 7332.4867259292405, 6800.047993280945, 6801.387875091166, 6846.657667116624, 6872.965635171892, 6884.211894182864, 3875.9612403876163, 3903.4925459690626, 3907.492026036597, 3909.4526766252748, 3910.9413358799643, 7527.772678333089, 7757.340626046736, 7802.765917022499, 7818.179091195457, 7822.6088695564995, 7540.924590754023, 7605.543667379975, 7615.771526854203, 7621.885671714928, 7630.656620452093, 7006.299370063055, 7739.535627862234, 7776.494527855672, 7786.260305271104, 7793.1427542969095, 7388.113426388868, 7398.038255026812, 7398.96300518494, 7401.5189012714, 7407.888881666671, 6979.755708550177, 7218.530795498275, 7234.529755565872, 7244.384227340735, 7275.454340924346, 7699.8845017323665, 7769.92230077692, 7777.300042996158, 7778.961105194438, 7786.338161256356, 3869.438931354994, 4693.037927224979, 4701.247800351927, 4711.693739906895, 4715.339852420732, 7316.122065352191, 7330.157031941395, 7334.596597187206, 7338.486305958745, 7345.632718363953, 6842.828929276808, 7087.7164913402885, 7097.609631470319, 7097.787066388017, 7138.892916606152, 7004.3696067354185, 7011.719531218898, 7044.119485064367, 7071.540349877448, 7087.681054352463, 6965.5124141309625, 6977.267386924503, 6984.231734509099, 6991.7902462926195, 7030.015797788416, 7126.786196413908, 7135.892961605477, 7136.215016348301, 7142.64286785669, 7145.82135446618, 6646.468282537482, 6731.259561448334, 6945.479089068239, 6987.056747339044, 6989.8951515726285], "value": 5155.092007694412, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [704.5350068954491, 719.0723966083751, 728.5555810955316, 740.0786021403354, 757.5984728094109, 15295.2352382381, 15491.612709682235, 15498.457553985612, 15503.3023513942, 15512.224388780558, 11542.307461552313, 14560.126392416463, 14561.708765824676, 14575.0, 14598.978071534997, 731.2796895058364, 779.4154384211841, 963.3641796414367, 966.5843687214496, 979.794243208927, 975.2246963663869, 1000.3897622450299, 1000.6297669862162, 1001.7946321004213, 1004.1113614451227, 1043.572135424476, 1048.7273308939693, 1049.979000419991, 1050.76883085721, 1050.8844027157006, 852.3649880838784, 1036.3056751976194, 1046.8639076920028, 1050.6847945616323, 1069.107295408335, 786.3041208530434, 788.0267869181556, 788.3141666749907, 789.0531362365185, 789.7472808701218, 12680.6829829255, 15382.538523844303, 15443.768343474856, 15473.690526189526, 15486.922565387102, 12800.103992720513, 13145.277009764459, 13162.26279664437, 13214.0, 13216.405261763244, 10207.693769186937, 10337.9483102584, 10439.582416703399, 10481.161507079416, 10555.997180267863, 3413.8805141819917, 3476.9826150869335, 3572.696320812734, 3595.227026189356, 3615.2950174715943, 81281.59359203166, 82968.0, 83143.0, 83175.0, 83299.0, 9568.0, 9605.903940960641, 9648.614055437703, 9768.316217864824, 10413.791724165474, 18834.71747923782, 21317.147314107573, 22347.21784737559, 22366.217182398865, 22370.44073898133, 7479.626018699043, 7565.773026809205, 7639.847203056019, 7654.923450765421, 7673.232676732394, 4087.97956010224, 4201.264778663722, 4232.343986682069, 4283.464566929134, 4314.9784251078545, 3620.366435873764, 3696.537932758405, 3696.77819330841, 3697.020289623252, 3702.7222958278235, 629.3894921925724, 647.757091090841, 648.2027106658836, 655.0338251079714, 670.3732010570133, 538.8356551251827, 614.8063360041573, 622.2004723929819, 624.8188025472562, 641.2881701311541, 7368.668409921566, 7709.51505951905, 8270.627821748036, 8318.70884519039, 8329.416940814084, 593.9309243361931, 602.6835911146595, 613.6285402126244, 619.5724949784634, 631.4190944331226, 3479.6868281854754, 3714.7434148857405, 3868.7485313454536, 3917.882463526099, 3926.8429262829736, 87255.56372218099, 87269.56365218133, 89313.55343223242, 90092.0, 90631.54684226536, 70358.2964170362, 70656.64671676609, 74275.62862185763, 75324.62337688386, 78979.0, 70793.64603176952, 71655.0, 71751.56496870033, 71941.2805871945, 72447.63776181191, 7738.806529836799, 7738.8452230953935, 7746.30283274508, 7810.0, 7879.172686867796, 37949.43075853809, 47917.76041119912, 49498.25752613786, 50398.0, 51844.963100738525, 66755.33244667492, 66836.0, 67102.32244193934, 67715.66142169258, 68428.0, 9872.901270987486, 10087.04173103554, 10101.292909496266, 10210.795784084425, 10617.734556636145, 10428.687139385831, 10840.945795270973, 11043.337399756041, 11155.665330040112, 11292.266002709797, 8824.5587720615, 10933.289336193122, 11301.208915376174, 11619.76760464769, 11993.554212960227, 11298.66104016848, 11365.829512557153, 11553.711157221136, 11657.88342116602, 11693.181477296508, 10511.0, 10907.509162087883, 11001.119910406987, 11026.669199924016, 11317.773644527228, 9522.61909523639, 9654.082862128087, 9673.22614190849, 9689.70930872075, 9710.126088652056, 7250.492465527574, 9318.0, 9489.525523723649, 9528.761780955532, 9560.569774360032], "value": 6944.786786292847, "unit": "score"}], "name": "dromaeo_css", "value": 7582.078083222943, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 111.0, 119.0, 111.0, 112.0, 111.0, 113.0, 113.0, 113.0, 135.0], "value": 115.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [162.0, 151.0, 183.0, 163.0, 118.0, 138.0, 117.0, 153.0, 153.0, 112.0], "value": 145.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 184.0, 192.0, 185.0, 183.0, 191.0, 256.0, 186.0, 184.0, 184.0], "value": 193.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 78.0, 77.0, 157.0, 54.0, 56.0, 55.0, 61.0, 137.0, 55.0], "value": 80.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 140.0, 79.0, 88.0, 87.0, 121.0, 77.0, 80.0, 127.0], "value": 98.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 88.0, 85.0, 88.0, 84.0, 90.0, 86.0, 88.0, 84.0], "value": 86.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 93.0, 93.0, 93.0, 94.0, 93.0, 93.0, 92.0, 93.0], "value": 93.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 85.0, 84.0, 84.0, 83.0, 84.0, 85.0, 85.0, 85.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 62.0, 60.0, 61.0, 63.0, 63.0, 63.0, 78.0, 57.0, 56.0], "value": 62.2, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 65.0, 72.0, 75.0, 75.0, 69.0, 67.0, 63.0, 65.0, 65.0], "value": 67.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 80.0, 80.0, 79.0, 80.0, 79.0, 79.0, 79.0, 79.0, 84.0], "value": 79.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 129.0, 134.0, 141.0, 144.0, 124.0, 124.0, 123.0, 172.0, 162.0], "value": 138.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 170.0, 163.0, 162.0, 163.0, 163.0, 160.0, 161.0, 166.0, 160.0], "value": 165.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 64.0, 65.0, 68.0, 65.0, 66.0, 66.0, 66.0, 67.0, 66.0], "value": 66.1, "unit": "score"}], "name": "kraken", "value": 1475.3999999999999, "alertThreshold": 2.0}]} 04:27:25 INFO - Return code: 0 04:27:25 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:27:25 ERROR - # TBPL SUCCESS # 04:27:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:27:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:27:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:27:25 INFO - 2016-07-07 04:27:25.385 screenresolution[1677:9980] starting screenresolution argv=screenresolution get 04:27:25 INFO - 2016-07-07 04:27:25.387 screenresolution[1677:9980] Display 0: 1600x1200x32@60 04:27:25 INFO - 2016-07-07 04:27:25.393 screenresolution[1678:9983] starting screenresolution argv=screenresolution list 04:27:25 INFO - Available Modes on Display 0 04:27:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:27:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:27:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:27:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:27:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:27:25 INFO - Intel Iris: 04:27:25 INFO - Chipset Model: Intel Iris 04:27:25 INFO - Type: GPU 04:27:25 INFO - Bus: Built-In 04:27:25 INFO - VRAM (Dynamic, Max): 1536 MB 04:27:25 INFO - Vendor: Intel (0x8086) 04:27:25 INFO - Device ID: 0x0a2e 04:27:25 INFO - Revision ID: 0x0009 04:27:25 INFO - Displays: 04:27:25 INFO - Display: 04:27:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:27:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:27:25 INFO - Display Serial Number: 1600x1200 60 04:27:25 INFO - Main Display: Yes 04:27:25 INFO - Mirror: Off 04:27:25 INFO - Online: Yes 04:27:25 INFO - Rotation: Supported 04:27:25 INFO - Return code: 0 04:27:25 INFO - Running post-action listener: _resource_record_post_action 04:27:25 INFO - Running post-run listener: _resource_record_post_run 04:27:26 INFO - Total resource usage - Wall time: 1194s; CPU: 53.0%; Read bytes: 37860864; Write bytes: 492589568; Read time: 447; Write time: 1700 04:27:26 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 183001600; Write bytes: 177567232; Read time: 16624; Write time: 429 04:27:26 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 36926976; Write bytes: 295685120; Read time: 433; Write time: 1216 04:27:26 INFO - Running post-run listener: _upload_blobber_files 04:27:26 INFO - Blob upload gear active. 04:27:26 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:27:26 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:27:26 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-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:27:26 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-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:27:27 INFO - (blobuploader) - INFO - Open directory for files ... 04:27:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:27:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:27:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:27:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:27:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:27:28 INFO - (blobuploader) - INFO - Done attempting. 04:27:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:27:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:27:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:27:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:27:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:27:29 INFO - (blobuploader) - INFO - Done attempting. 04:27:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:27:29 INFO - Return code: 0 04:27:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:27:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:27:29 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97977ea748204c326ba7a07eeb94a8fd8df44d7999e379d7b598de08450a1aa504351196065670364e9645e97023d73503fc56dfada457041fcc30e219a68d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95badfb25c9c5027a76b80f0cf252aaa1c757f2cb7f84548858f4b9216bea67a7e8dd3cd06c7f39f98522f6d3da2a7fadb9e1aedc1d3ff68a35d22dbcf628dc7"} 04:27:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:27:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:27:29 INFO - Contents: 04:27:29 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97977ea748204c326ba7a07eeb94a8fd8df44d7999e379d7b598de08450a1aa504351196065670364e9645e97023d73503fc56dfada457041fcc30e219a68d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95badfb25c9c5027a76b80f0cf252aaa1c757f2cb7f84548858f4b9216bea67a7e8dd3cd06c7f39f98522f6d3da2a7fadb9e1aedc1d3ff68a35d22dbcf628dc7"} 04:27:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:27:29 INFO - Copying logs to upload dir... 04:27:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:27:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.076610 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2016-07-07 04:27:29.686894) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 04:27:29.690450) ========= 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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97977ea748204c326ba7a07eeb94a8fd8df44d7999e379d7b598de08450a1aa504351196065670364e9645e97023d73503fc56dfada457041fcc30e219a68d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95badfb25c9c5027a76b80f0cf252aaa1c757f2cb7f84548858f4b9216bea67a7e8dd3cd06c7f39f98522f6d3da2a7fadb9e1aedc1d3ff68a35d22dbcf628dc7"} build_url:https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011178 build_url: 'https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/97977ea748204c326ba7a07eeb94a8fd8df44d7999e379d7b598de08450a1aa504351196065670364e9645e97023d73503fc56dfada457041fcc30e219a68d40", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/95badfb25c9c5027a76b80f0cf252aaa1c757f2cb7f84548858f4b9216bea67a7e8dd3cd06c7f39f98522f6d3da2a7fadb9e1aedc1d3ff68a35d22dbcf628dc7"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-07 04:27:29.722730) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 04:27:29.723150) ========= 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.Hw2T5YXHMG/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.Mtxlra5KDQ/Listeners TMPDIR=/var/folders/9v/3t33my4n45z9l7y7__3lqm9r00000w/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.005234 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-07 04:27:29.773543) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 04:27:29.773866) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-07 04:27:29.774163) ========= ========= Total master_lag: 0.20 =========