builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0077 starttime: 1467871506.42 results: success (0) buildid: 20160706215822 builduid: 005acac198204b229cc2500b323a4168 revision: d142c49033c015f67272562b37dbe2912cfc7f14 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:06.422024) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:06.422432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:06.422734) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:06.452508) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:06.452863) ========= 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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-06 23:05:06-- 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% 150M=0s 2016-07-06 23:05:06 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.102777 ========= master_lag: 0.49 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:07.045173) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:07.045533) ========= 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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.028084 ========= master_lag: 0.11 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:07.186374) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 23:05:07.186769) ========= 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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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-06 23:05:07,262 truncating revision to first 12 chars 2016-07-06 23:05:07,262 Setting DEBUG logging. 2016-07-06 23:05:07,262 attempt 1/10 2016-07-06 23:05:07,262 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-06 23:05:08,507 unpacking tar archive at: mozilla-beta-d142c49033c0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.520592 ========= master_lag: 0.48 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-06 23:05:09.185169) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:09.185599) ========= script_repo_revision: d142c49033c015f67272562b37dbe2912cfc7f14 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:09.186070) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:09.186343) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-06 23:05:09.208235) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 40 secs) (at 2016-07-06 23:05:09.208625) ========= /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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:09 INFO - MultiFileLogger online at 20160706 23:05:09 in /builds/slave/test 23:05:09 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 23:05:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 23:05:09 INFO - {'append_to_log': False, 23:05:09 INFO - 'base_work_dir': '/builds/slave/test', 23:05:09 INFO - 'blob_upload_branch': 'Mozilla-Beta', 23:05:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 23:05:09 INFO - 'branch': 'Mozilla-Beta', 23:05:09 INFO - 'buildbot_json_path': 'buildprops.json', 23:05:09 INFO - 'config_files': ('talos/mac_config.py',), 23:05:09 INFO - 'default_actions': ('clobber', 23:05:09 INFO - 'read-buildbot-config', 23:05:09 INFO - 'download-and-extract', 23:05:09 INFO - 'populate-webroot', 23:05:09 INFO - 'create-virtualenv', 23:05:09 INFO - 'install', 23:05:09 INFO - 'run-tests'), 23:05:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 23:05:09 INFO - 'download_minidump_stackwalk': True, 23:05:09 INFO - 'download_symbols': 'ondemand', 23:05:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 23:05:09 INFO - 'tooltool.py': '/tools/tooltool.py', 23:05:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 23:05:09 INFO - '/tools/misc-python/virtualenv.py')}, 23:05:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 23:05:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 23:05:09 INFO - 'installer_path': 'installer.exe', 23:05:09 INFO - 'log_level': 'info', 23:05:09 INFO - 'log_name': 'talos', 23:05:09 INFO - 'log_to_console': True, 23:05:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 23:05:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 23:05:09 INFO - 'opt_config_files': (), 23:05:09 INFO - 'pip_index': False, 23:05:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:05:09 INFO - 'cmd': ('bash', 23:05:09 INFO - '-c', 23:05:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:05:09 INFO - 'enabled': True, 23:05:09 INFO - 'halt_on_failure': False, 23:05:09 INFO - 'name': 'check_screen_resolution'},), 23:05:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 23:05:09 INFO - 'cmd': ('bash', 23:05:09 INFO - '-c', 23:05:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 23:05:09 INFO - 'enabled': True, 23:05:09 INFO - 'halt_on_failure': False, 23:05:09 INFO - 'name': 'check_screen_resolution'},), 23:05:09 INFO - 'run_cmd_checks_enabled': True, 23:05:09 INFO - 'sps_profile': False, 23:05:09 INFO - 'sps_profile_interval': 0, 23:05:09 INFO - 'suite': 'dromaeojs', 23:05:09 INFO - 'system_bits': '32', 23:05:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 23:05:09 INFO - 'title': 't-yosemite-r7-0077', 23:05:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 23:05:09 INFO - 'use_talos_json': True, 23:05:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 23:05:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 23:05:09 INFO - 'work_dir': 'build'} 23:05:09 INFO - ##### 23:05:09 INFO - ##### Running clobber step. 23:05:09 INFO - ##### 23:05:09 INFO - Running pre-action listener: _resource_record_pre_action 23:05:09 INFO - Running main action method: clobber 23:05:09 INFO - rmtree: /builds/slave/test/build 23:05:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 23:05:11 INFO - Running post-action listener: _resource_record_post_action 23:05:11 INFO - ##### 23:05:11 INFO - ##### Running read-buildbot-config step. 23:05:11 INFO - ##### 23:05:11 INFO - Running pre-action listener: _resource_record_pre_action 23:05:11 INFO - Running main action method: read_buildbot_config 23:05:11 INFO - Using buildbot properties: 23:05:11 INFO - { 23:05:11 INFO - "project": "", 23:05:11 INFO - "product": "firefox", 23:05:11 INFO - "script_repo_revision": "production", 23:05:11 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 23:05:11 INFO - "repository": "", 23:05:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 23:05:11 INFO - "buildid": "20160706215822", 23:05:11 INFO - "pgo_build": "False", 23:05:11 INFO - "basedir": "/builds/slave/test", 23:05:11 INFO - "buildnumber": 26, 23:05:11 INFO - "slavename": "t-yosemite-r7-0077", 23:05:11 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 23:05:11 INFO - "platform": "yosemite_r7", 23:05:11 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 23:05:11 INFO - "branch": "mozilla-beta", 23:05:11 INFO - "repo_path": "releases/mozilla-beta", 23:05:11 INFO - "revision": "d142c49033c015f67272562b37dbe2912cfc7f14", 23:05:11 INFO - "stage_platform": "macosx64", 23:05:11 INFO - "builduid": "005acac198204b229cc2500b323a4168", 23:05:11 INFO - "slavebuilddir": "test" 23:05:11 INFO - } 23:05:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 23:05:11 INFO - Running post-action listener: _resource_record_post_action 23:05:11 INFO - ##### 23:05:11 INFO - ##### Running download-and-extract step. 23:05:11 INFO - ##### 23:05:11 INFO - Running pre-action listener: _resource_record_pre_action 23:05:11 INFO - Running main action method: download_and_extract 23:05:11 INFO - mkdir: /builds/slave/test/build/tests 23:05:11 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')]} 23:05:11 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 23:05:11 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 23:05:11 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 23:05:11 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 23:05:11 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 23:05:13 INFO - Downloaded 1165 bytes. 23:05:13 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 23:05:13 INFO - Using the following test package requirements: 23:05:13 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 23:05:13 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 23:05:13 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 23:05:13 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 23:05:13 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 23:05:13 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 23:05:13 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 23:05:13 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 23:05:13 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 23:05:13 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 23:05:13 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 23:05:13 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 23:05:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:13 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 23:05:13 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 23:05:13 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 23:05:13 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 23:05:13 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 23:05:17 INFO - Downloaded 18351388 bytes. 23:05:17 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'] 23:05:17 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 23:05:20 INFO - Return code: 0 23:05:20 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 23:05:20 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')]} 23:05:20 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 23:05:20 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 23:05:20 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 23:05:20 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 23:05:20 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 23:05:24 INFO - Downloaded 18351388 bytes. 23:05:24 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'] 23:05:24 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 23:05:28 INFO - Return code: 0 23:05:28 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')]} 23:05:28 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 23:05:28 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 23:05:28 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 23:05:28 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 23:05:28 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 23:05:29 INFO - Downloaded 10840145 bytes. 23:05:29 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'] 23:05:29 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 23:05:29 INFO - Return code: 0 23:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:29 INFO - https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 23:05:29 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 23:05:29 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 23:05:29 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 23:05:29 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 23:05:32 INFO - Downloaded 86189077 bytes. 23:05:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 23:05:32 INFO - mkdir: /builds/slave/test/properties 23:05:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 23:05:32 INFO - Writing to file /builds/slave/test/properties/build_url 23:05:32 INFO - Contents: 23:05:32 INFO - build_url:https://queue.taskcluster.net/v1/task/FeiigZlwTaKgLwp6GbYdWQ/artifacts/public/build/firefox-48.0.en-US.mac.dmg 23:05:33 INFO - Running post-action listener: _resource_record_post_action 23:05:33 INFO - Running post-action listener: set_extra_try_arguments 23:05:33 INFO - ##### 23:05:33 INFO - ##### Running populate-webroot step. 23:05:33 INFO - ##### 23:05:33 INFO - Running pre-action listener: _resource_record_pre_action 23:05:33 INFO - Running main action method: populate_webroot 23:05:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 23:05:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 23:05:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 23:05:33 INFO - '--tppagecycles', 23:05:33 INFO - '7'], 23:05:33 INFO - 'tests': ['tsvgm']}}, 23:05:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 23:05:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 23:05:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 23:05:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 23:05:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:05:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 23:05:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['damp', 'tps']}, 23:05:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'tests': ['damp', 'tps']}, 23:05:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['dromaeo_dom']}, 23:05:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 23:05:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['a11yr', 23:05:33 INFO - 'ts_paint', 23:05:33 INFO - 'tpaint', 23:05:33 INFO - 'sessionrestore', 23:05:33 INFO - 'sessionrestore_no_auto_restore', 23:05:33 INFO - 'tabpaint']}, 23:05:33 INFO - 'other-e10s': {'tests': ['a11yr', 23:05:33 INFO - 'ts_paint', 23:05:33 INFO - 'tpaint', 23:05:33 INFO - 'sessionrestore', 23:05:33 INFO - 'sessionrestore_no_auto_restore', 23:05:33 INFO - 'tabpaint']}, 23:05:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 23:05:33 INFO - 'ts_paint', 23:05:33 INFO - 'tpaint', 23:05:33 INFO - 'sessionrestore', 23:05:33 INFO - 'sessionrestore_no_auto_restore', 23:05:33 INFO - 'tabpaint']}, 23:05:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 23:05:33 INFO - 'ts_paint', 23:05:33 INFO - 'tpaint', 23:05:33 INFO - 'sessionrestore', 23:05:33 INFO - 'sessionrestore_no_auto_restore', 23:05:33 INFO - 'tabpaint']}, 23:05:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['a11yr', 23:05:33 INFO - 'ts_paint', 23:05:33 INFO - 'tpaint', 23:05:33 INFO - 'sessionrestore', 23:05:33 INFO - 'sessionrestore_no_auto_restore', 23:05:33 INFO - 'tabpaint']}, 23:05:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['a11yr', 23:05:33 INFO - 'ts_paint', 23:05:33 INFO - 'tpaint', 23:05:33 INFO - 'sessionrestore', 23:05:33 INFO - 'sessionrestore_no_auto_restore', 23:05:33 INFO - 'tabpaint']}, 23:05:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['tsvgx', 23:05:33 INFO - 'tsvgr_opacity', 23:05:33 INFO - 'tart', 23:05:33 INFO - 'tscrollx', 23:05:33 INFO - 'cart']}, 23:05:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 23:05:33 INFO - 'tsvgr_opacity', 23:05:33 INFO - 'tart', 23:05:33 INFO - 'tscrollx', 23:05:33 INFO - 'cart']}, 23:05:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'talos_options': ['--disable-e10s'], 23:05:33 INFO - 'tests': ['tp5o']}, 23:05:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'tests': ['tp5o']}, 23:05:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'talos_options': ['--disable-e10s', 23:05:33 INFO - '--xperf_path', 23:05:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:05:33 INFO - 'tests': ['tp5n']}, 23:05:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 23:05:33 INFO - 'talos_options': ['--xperf_path', 23:05:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 23:05:33 INFO - 'tests': ['tp5n']}}, 23:05:33 INFO - 'talos.zip': {'path': '', 23:05:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 23:05:33 INFO - Running post-action listener: _resource_record_post_action 23:05:33 INFO - ##### 23:05:33 INFO - ##### Running create-virtualenv step. 23:05:33 INFO - ##### 23:05:33 INFO - Running pre-action listener: _resource_record_pre_action 23:05:33 INFO - Running main action method: create_virtualenv 23:05:33 INFO - Creating virtualenv /builds/slave/test/build/venv 23:05:33 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 23:05:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 23:05:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 23:05:33 INFO - Using real prefix '/tools/python27' 23:05:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 23:05:33 INFO - Installing distribute.............................................................................................................................................................................................done. 23:05:36 INFO - Installing pip.................done. 23:05:36 INFO - Return code: 0 23:05:36 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 23:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:36 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 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:36 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 23:05:36 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 23:05:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:36 INFO - 'HOME': '/Users/cltbld', 23:05:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:36 INFO - 'LOGNAME': 'cltbld', 23:05:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:36 INFO - 'MOZ_NO_REMOTE': '1', 23:05:36 INFO - 'NO_EM_RESTART': '1', 23:05:36 INFO - 'PAGER': '/bin/cat', 23:05:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:36 INFO - 'PWD': '/builds/slave/test', 23:05:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:36 INFO - 'SHELL': '/bin/bash', 23:05:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:36 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:36 INFO - 'USER': 'cltbld', 23:05:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:36 INFO - 'XPC_FLAGS': '0x0', 23:05:36 INFO - 'XPC_SERVICE_NAME': '0', 23:05:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:37 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 23:05:37 INFO - Cleaning up... 23:05:37 INFO - Return code: 0 23:05:37 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 23:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:37 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 23:05:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 23:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:37 INFO - 'HOME': '/Users/cltbld', 23:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:37 INFO - 'LOGNAME': 'cltbld', 23:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:37 INFO - 'MOZ_NO_REMOTE': '1', 23:05:37 INFO - 'NO_EM_RESTART': '1', 23:05:37 INFO - 'PAGER': '/bin/cat', 23:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:37 INFO - 'PWD': '/builds/slave/test', 23:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:37 INFO - 'SHELL': '/bin/bash', 23:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:37 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:37 INFO - 'USER': 'cltbld', 23:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:37 INFO - 'XPC_FLAGS': '0x0', 23:05:37 INFO - 'XPC_SERVICE_NAME': '0', 23:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:37 INFO - Downloading/unpacking psutil>=0.7.1 23:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 23:05:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:05:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:05:40 INFO - Installing collected packages: psutil 23:05:40 INFO - Running setup.py install for psutil 23:05:40 INFO - building 'psutil._psutil_osx' extension 23:05:40 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 23:05:40 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 23:05:40 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 23:05:41 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 23:05:41 INFO - building 'psutil._psutil_posix' extension 23:05:41 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 23:05:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 23:05:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 23:05:41 INFO - ^ 23:05:41 INFO - 1 warning generated. 23:05:41 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 23:05:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 23:05:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 23:05:41 INFO - Successfully installed psutil 23:05:41 INFO - Cleaning up... 23:05:41 INFO - Return code: 0 23:05:41 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 23:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 23:05:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 23:05:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:41 INFO - 'HOME': '/Users/cltbld', 23:05:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:41 INFO - 'LOGNAME': 'cltbld', 23:05:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:41 INFO - 'MOZ_NO_REMOTE': '1', 23:05:41 INFO - 'NO_EM_RESTART': '1', 23:05:41 INFO - 'PAGER': '/bin/cat', 23:05:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:41 INFO - 'PWD': '/builds/slave/test', 23:05:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:41 INFO - 'SHELL': '/bin/bash', 23:05:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:41 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:41 INFO - 'USER': 'cltbld', 23:05:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:41 INFO - 'XPC_FLAGS': '0x0', 23:05:41 INFO - 'XPC_SERVICE_NAME': '0', 23:05:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:41 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 23:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:44 INFO - Downloading mozsystemmonitor-0.0.tar.gz 23:05:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 23:05:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 23:05:44 INFO - Installing collected packages: mozsystemmonitor 23:05:44 INFO - Running setup.py install for mozsystemmonitor 23:05:44 INFO - Successfully installed mozsystemmonitor 23:05:44 INFO - Cleaning up... 23:05:44 INFO - Return code: 0 23:05:44 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 23:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 23:05:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 23:05:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:44 INFO - 'HOME': '/Users/cltbld', 23:05:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:44 INFO - 'LOGNAME': 'cltbld', 23:05:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:44 INFO - 'MOZ_NO_REMOTE': '1', 23:05:44 INFO - 'NO_EM_RESTART': '1', 23:05:44 INFO - 'PAGER': '/bin/cat', 23:05:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:44 INFO - 'PWD': '/builds/slave/test', 23:05:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:44 INFO - 'SHELL': '/bin/bash', 23:05:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:44 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:44 INFO - 'USER': 'cltbld', 23:05:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:44 INFO - 'XPC_FLAGS': '0x0', 23:05:44 INFO - 'XPC_SERVICE_NAME': '0', 23:05:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:44 INFO - Downloading/unpacking blobuploader==1.2.4 23:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:47 INFO - Downloading blobuploader-1.2.4.tar.gz 23:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 23:05:47 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 23:05:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 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 23:05:47 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 23:05:48 INFO - Downloading docopt-0.6.1.tar.gz 23:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 23:05:48 INFO - Installing collected packages: blobuploader, requests, docopt 23:05:48 INFO - Running setup.py install for blobuploader 23:05:48 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 23:05:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 23:05:48 INFO - Running setup.py install for requests 23:05:49 INFO - Running setup.py install for docopt 23:05:49 INFO - Successfully installed blobuploader requests docopt 23:05:49 INFO - Cleaning up... 23:05:49 INFO - Return code: 0 23:05:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:49 INFO - 'HOME': '/Users/cltbld', 23:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:49 INFO - 'LOGNAME': 'cltbld', 23:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:49 INFO - 'MOZ_NO_REMOTE': '1', 23:05:49 INFO - 'NO_EM_RESTART': '1', 23:05:49 INFO - 'PAGER': '/bin/cat', 23:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:49 INFO - 'PWD': '/builds/slave/test', 23:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:49 INFO - 'SHELL': '/bin/bash', 23:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:49 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:49 INFO - 'USER': 'cltbld', 23:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:49 INFO - 'XPC_FLAGS': '0x0', 23:05:49 INFO - 'XPC_SERVICE_NAME': '0', 23:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:05:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uajOcl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:05:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-USdZ5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:05:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-bvK6or-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:05:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:05:49 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-KNa7Ju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-9I45Sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-DvwMlR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-ctV7AO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-onEGWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-n50PzA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-XLwfi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-XjvINe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-MGBwQF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-uO5G2e-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:05:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:05:50 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-0eKoy9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:05:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-QC1Vrs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:05:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-UsPb_z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:05:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:05:51 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-u04XZQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:05:51 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 23:05:51 INFO - Running setup.py install for manifestparser 23:05:51 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 23:05:51 INFO - Running setup.py install for mozcrash 23:05:51 INFO - Running setup.py install for mozdebug 23:05:51 INFO - Running setup.py install for mozdevice 23:05:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 23:05:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 23:05:51 INFO - Running setup.py install for mozfile 23:05:52 INFO - Running setup.py install for mozhttpd 23:05:52 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Running setup.py install for mozinfo 23:05:52 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Running setup.py install for mozInstall 23:05:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Running setup.py install for mozleak 23:05:52 INFO - Running setup.py install for mozlog 23:05:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Running setup.py install for moznetwork 23:05:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 23:05:52 INFO - Running setup.py install for mozprocess 23:05:53 INFO - Running setup.py install for mozprofile 23:05:53 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 23:05:53 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 23:05:53 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 23:05:53 INFO - Running setup.py install for mozrunner 23:05:53 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 23:05:53 INFO - Running setup.py install for mozscreenshot 23:05:53 INFO - Running setup.py install for moztest 23:05:53 INFO - Running setup.py install for mozversion 23:05:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 23:05:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 23:05:53 INFO - Cleaning up... 23:05:53 INFO - Return code: 0 23:05:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:05: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')]} 23:05:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05: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')]} 23:05:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 23:05:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 23:05:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:53 INFO - 'HOME': '/Users/cltbld', 23:05:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:53 INFO - 'LOGNAME': 'cltbld', 23:05:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:53 INFO - 'MOZ_NO_REMOTE': '1', 23:05:53 INFO - 'NO_EM_RESTART': '1', 23:05:53 INFO - 'PAGER': '/bin/cat', 23:05:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:53 INFO - 'PWD': '/builds/slave/test', 23:05:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:53 INFO - 'SHELL': '/bin/bash', 23:05:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:53 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:53 INFO - 'USER': 'cltbld', 23:05:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:53 INFO - 'XPC_FLAGS': '0x0', 23:05:53 INFO - 'XPC_SERVICE_NAME': '0', 23:05:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-5O5Xvi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-fEhRDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-H0iYEa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-sMVAe_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-UbCzbP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-G1EUlX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-QsOzID-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 23:05:54 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)) 23:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 23:05:54 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-C9I6G3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-gMw8_x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-vPOhiM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-nDhmuK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-GFNbGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-S4p0yI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-3iyMdc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-EVwM2H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-7yDKEj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 23:05:55 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)) 23:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 23:05:55 INFO - Running setup.py (path:/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/pip-6y6QHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 23:05:56 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)) 23:05:56 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)) 23:05:56 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)) 23:05:56 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)) 23:05:56 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)) 23:05:56 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)) 23:05:56 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 23:05:56 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 23:05:56 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 23:05:56 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 23:05:56 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 23:05:56 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 23:05:56 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 23:05:58 INFO - Downloading blessings-1.6.tar.gz 23:05:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 23:05:58 INFO - Installing collected packages: blessings 23:05:58 INFO - Running setup.py install for blessings 23:05:58 INFO - Successfully installed blessings 23:05:58 INFO - Cleaning up... 23:05:58 INFO - Return code: 0 23:05:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 23:05:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:05:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:05:59 INFO - Reading from file tmpfile_stdout 23:05:59 INFO - Current package versions: 23:05:59 INFO - blessings == 1.6 23:05:59 INFO - blobuploader == 1.2.4 23:05:59 INFO - docopt == 0.6.1 23:05:59 INFO - manifestparser == 1.1 23:05:59 INFO - mozInstall == 1.12 23:05:59 INFO - mozcrash == 0.17 23:05:59 INFO - mozdebug == 0.1 23:05:59 INFO - mozdevice == 0.48 23:05:59 INFO - mozfile == 1.2 23:05:59 INFO - mozhttpd == 0.7 23:05:59 INFO - mozinfo == 0.9 23:05:59 INFO - mozleak == 0.1 23:05:59 INFO - mozlog == 3.1 23:05:59 INFO - moznetwork == 0.27 23:05:59 INFO - mozprocess == 0.22 23:05:59 INFO - mozprofile == 0.28 23:05:59 INFO - mozrunner == 6.11 23:05:59 INFO - mozscreenshot == 0.1 23:05:59 INFO - mozsystemmonitor == 0.0 23:05:59 INFO - moztest == 0.7 23:05:59 INFO - mozversion == 1.4 23:05:59 INFO - psutil == 3.1.1 23:05:59 INFO - requests == 1.2.3 23:05:59 INFO - wsgiref == 0.1.2 23:05:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 23:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:59 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 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:59 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 23:05:59 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 23:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:59 INFO - 'HOME': '/Users/cltbld', 23:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:59 INFO - 'LOGNAME': 'cltbld', 23:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:59 INFO - 'MOZ_NO_REMOTE': '1', 23:05:59 INFO - 'NO_EM_RESTART': '1', 23:05:59 INFO - 'PAGER': '/bin/cat', 23:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:59 INFO - 'PWD': '/builds/slave/test', 23:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:59 INFO - 'SHELL': '/bin/bash', 23:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:59 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:59 INFO - 'USER': 'cltbld', 23:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:59 INFO - 'XPC_FLAGS': '0x0', 23:05:59 INFO - 'XPC_SERVICE_NAME': '0', 23:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 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)) 23:05:59 INFO - Cleaning up... 23:05:59 INFO - Return code: 0 23:05:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 23:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:05:59 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:05:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 23:05:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 23:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:05:59 INFO - 'HOME': '/Users/cltbld', 23:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:05:59 INFO - 'LOGNAME': 'cltbld', 23:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:05:59 INFO - 'MOZ_NO_REMOTE': '1', 23:05:59 INFO - 'NO_EM_RESTART': '1', 23:05:59 INFO - 'PAGER': '/bin/cat', 23:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:05:59 INFO - 'PWD': '/builds/slave/test', 23:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:05:59 INFO - 'SHELL': '/bin/bash', 23:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:05:59 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:05:59 INFO - 'USER': 'cltbld', 23:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:05:59 INFO - 'XPC_FLAGS': '0x0', 23:05:59 INFO - 'XPC_SERVICE_NAME': '0', 23:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:05:59 INFO - Downloading/unpacking jsonschema 23:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:05:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:05:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:02 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 23:06:02 INFO - Downloading/unpacking functools32 (from jsonschema) 23:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:02 INFO - Downloading functools32-3.2.3-2.tar.gz 23:06:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 23:06:02 INFO - warning: no files found matching '*.txt' 23:06:02 INFO - no previously-included directories found matching 'build' 23:06:02 INFO - no previously-included directories found matching 'dist' 23:06:02 INFO - no previously-included directories found matching '.git*' 23:06:02 INFO - Installing collected packages: jsonschema, functools32 23:06:02 INFO - Running setup.py install for functools32 23:06:02 INFO - warning: no files found matching '*.txt' 23:06:02 INFO - no previously-included directories found matching 'build' 23:06:02 INFO - no previously-included directories found matching 'dist' 23:06:02 INFO - no previously-included directories found matching '.git*' 23:06:02 INFO - Successfully installed jsonschema functools32 23:06:02 INFO - Cleaning up... 23:06:02 INFO - Return code: 0 23:06:02 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 23:06:02 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')]} 23:06:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 23:06:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 23:06:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 23:06:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 23:06:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10751ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10752e420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbd9d694a0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, '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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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 23:06:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 23:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 23:06:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:06:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:03 INFO - 'HOME': '/Users/cltbld', 23:06:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:03 INFO - 'LOGNAME': 'cltbld', 23:06:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:03 INFO - 'MOZ_NO_REMOTE': '1', 23:06:03 INFO - 'NO_EM_RESTART': '1', 23:06:03 INFO - 'PAGER': '/bin/cat', 23:06:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:03 INFO - 'PWD': '/builds/slave/test', 23:06:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:03 INFO - 'SHELL': '/bin/bash', 23:06:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:06:03 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:06:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:03 INFO - 'USER': 'cltbld', 23:06:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:03 INFO - 'XPC_FLAGS': '0x0', 23:06:03 INFO - 'XPC_SERVICE_NAME': '0', 23:06:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 23:06:03 INFO - Downloading/unpacking flake8 23:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 23:06:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 23:06:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 23:06:09 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 23:06:09 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 23:06:09 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 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 23:06:09 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 23:06:10 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06: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 23:06:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 23:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:06:10 INFO - no previously-included directories found matching 'docs/_build' 23:06:10 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 23:06:10 INFO - Running setup.py install for pep8 23:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 23:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 23:06:10 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 23:06:10 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 23:06:10 INFO - no previously-included directories found matching 'docs/_build' 23:06:10 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 23:06:10 INFO - Successfully installed flake8 mccabe pyflakes pep8 23:06:10 INFO - Cleaning up... 23:06:10 INFO - Return code: 0 23:06:10 INFO - Running post-action listener: _resource_record_post_action 23:06:10 INFO - Running post-action listener: _start_resource_monitoring 23:06:11 INFO - Starting resource monitoring. 23:06:11 INFO - ##### 23:06:11 INFO - ##### Running install step. 23:06:11 INFO - ##### 23:06:11 INFO - Running pre-action listener: _resource_record_pre_action 23:06:11 INFO - Running main action method: install 23:06:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 23:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 23:06:11 INFO - Reading from file tmpfile_stdout 23:06:11 INFO - Detecting whether we're running mozinstall >=1.0... 23:06:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 23:06:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 23:06:11 INFO - Reading from file tmpfile_stdout 23:06:11 INFO - Output received: 23:06:11 INFO - Usage: mozinstall [options] installer 23:06:11 INFO - Options: 23:06:11 INFO - -h, --help show this help message and exit 23:06:11 INFO - -d DEST, --destination=DEST 23:06:11 INFO - Directory to install application into. [default: 23:06:11 INFO - "/builds/slave/test"] 23:06:11 INFO - --app=APP Application being installed. [default: firefox] 23:06:11 INFO - mkdir: /builds/slave/test/build/application 23:06:11 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'] 23:06:11 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 23:06:33 INFO - Reading from file tmpfile_stdout 23:06:33 INFO - Output received: 23:06:33 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 23:06:33 INFO - Running post-action listener: _resource_record_post_action 23:06:33 INFO - ##### 23:06:33 INFO - ##### Running run-tests step. 23:06:33 INFO - ##### 23:06:33 INFO - Running pre-action listener: _resource_record_pre_action 23:06:33 INFO - Running main action method: run_tests 23:06:33 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:06:33 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:06:33 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:06:33 INFO - 2016-07-06 23:06:33.572 screenresolution[1642:7577] starting screenresolution argv=screenresolution get 23:06:33 INFO - 2016-07-06 23:06:33.574 screenresolution[1642:7577] Display 0: 1600x1200x32@60 23:06:33 INFO - 2016-07-06 23:06:33.580 screenresolution[1643:7579] starting screenresolution argv=screenresolution list 23:06:33 INFO - Available Modes on Display 0 23:06:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:06:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:06:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:06:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:06:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:06:33 INFO - Intel Iris: 23:06:33 INFO - Chipset Model: Intel Iris 23:06:33 INFO - Type: GPU 23:06:33 INFO - Bus: Built-In 23:06:33 INFO - VRAM (Dynamic, Max): 1536 MB 23:06:33 INFO - Vendor: Intel (0x8086) 23:06:33 INFO - Device ID: 0x0a2e 23:06:33 INFO - Revision ID: 0x0009 23:06:33 INFO - Displays: 23:06:33 INFO - Display: 23:06:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:06:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:06:33 INFO - Display Serial Number: 1600x1200 60 23:06:33 INFO - Main Display: Yes 23:06:33 INFO - Mirror: Off 23:06:33 INFO - Online: Yes 23:06:33 INFO - Rotation: Supported 23:06:33 INFO - Return code: 0 23:06:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 23:06:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 23:06:33 INFO - Python 2.7.3 23:06:33 INFO - Return code: 0 23:06:33 INFO - grabbing minidump binary from tooltool 23:06:33 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')]} 23:06:33 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 0x1073bde00>, '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 0x107226db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1073b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 23:06:33 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 23:06:33 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 23:06:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 23:06:33 INFO - Return code: 0 23:06:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 23:06:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 23:06:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 23:06:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 23:06:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 23:06:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 23:06:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 23:06:35 INFO - Return code: 0 23:06:35 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-0077', '--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 23:06:35 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-0077 --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 23:06:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Gfuy18DCwS/Render', 23:06:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 23:06:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 23:06:35 INFO - 'HOME': '/Users/cltbld', 23:06:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 23:06:35 INFO - 'LOGNAME': 'cltbld', 23:06:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 23:06:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 23:06:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 23:06:35 INFO - 'MOZ_NO_REMOTE': '1', 23:06:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 23:06:35 INFO - 'NO_EM_RESTART': '1', 23:06:35 INFO - 'PAGER': '/bin/cat', 23:06:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 23:06:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 23:06:35 INFO - 'PWD': '/builds/slave/test', 23:06:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 23:06:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 23:06:35 INFO - 'SHELL': '/bin/bash', 23:06:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.7uDyiPh3yN/Listeners', 23:06:35 INFO - 'TMPDIR': '/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/', 23:06:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 23:06:35 INFO - 'USER': 'cltbld', 23:06:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 23:06:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 23:06:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 23:06:35 INFO - 'XPC_FLAGS': '0x0', 23:06:35 INFO - 'XPC_SERVICE_NAME': '0', 23:06:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 23:06:35 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-0077', '--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 23:06:35 INFO - mozversion application_buildid: 20160706215822 23:06:35 INFO - mozversion application_changeset: d142c49033c015f67272562b37dbe2912cfc7f14 23:06:35 INFO - mozversion application_display_name: Firefox 23:06:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 23:06:35 INFO - mozversion application_name: Firefox 23:06:35 INFO - mozversion application_remotingname: firefox 23:06:35 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 23:06:35 INFO - mozversion application_vendor: Mozilla 23:06:35 INFO - mozversion application_version: 48.0 23:06:35 INFO - mozversion platform_buildid: 20160706215822 23:06:35 INFO - mozversion platform_changeset: d142c49033c015f67272562b37dbe2912cfc7f14 23:06:35 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 23:06:35 INFO - mozversion platform_version: 48.0 23:06:35 INFO - using testdate: 1467871595 23:06:35 INFO - actual date: 1467871595 23:06:35 INFO - starting webserver on 'localhost:49274' 23:06:35 INFO - SUITE-START | Running 2 tests 23:06:35 INFO - TEST-START | dromaeo_css 23:06:35 INFO - operating with platform_type : mac_ 23:06:35 INFO - Initialising browser for dromaeo_css test... 23:06:35 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpUGKWXi/profile http://localhost:49274/getInfo.html) 23:06:37 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 23:06:37 INFO - PROCESS | 1661 | colorDepth:24 23:06:37 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 23:06:37 INFO - PROCESS | 1661 | __metrics 23:06:37 INFO - PROCESS | 1661 | 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" 23:06:37 ERROR - PROCESS | 1661 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:06:38 INFO - TEST-INFO | 1661: exit 0 23:06:38 INFO - Browser initialized. 23:06:38 INFO - Running cycle 1/1 for dromaeo_css test... 23:06:38 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpUGKWXi/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:06:50 INFO - PROCESS | 1664 | RSS: Main: 161865728 23:06:50 INFO - PROCESS | 1664 | 23:07:41 INFO - PROCESS | 1664 | 1467871661188 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] 23:09:50 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 23:09:52 INFO - PROCESS | 1664 | RSS: Main: 318844928 23:09:52 INFO - PROCESS | 1664 | 23:12:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 23:12:55 INFO - PROCESS | 1664 | RSS: Main: 305782784 23:12:55 INFO - PROCESS | 1664 | 23:15:58 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 23:15:58 INFO - PROCESS | 1664 | RSS: Main: 310222848 23:15:58 INFO - PROCESS | 1664 | 23:19:00 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 23:19:01 INFO - PROCESS | 1664 | RSS: Main: 305197056 23:19:01 INFO - PROCESS | 1664 | 23:22:04 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 23:22:05 INFO - PROCESS | 1664 | RSS: Main: 274505728 23:22:05 INFO - PROCESS | 1664 | 23:25:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 23:25:07 INFO - PROCESS | 1664 | RSS: Main: 316575744 23:25:07 INFO - PROCESS | 1664 | 23:25:07 INFO - PROCESS | 1664 | __start_tp_report 23:25:07 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 23:25:07 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 23:25:07 INFO - PROCESS | 1664 | |i|pagename|runs| 23:25:07 INFO - PROCESS | 1664 | |0;dojo.html;3477.7565570410075;3554.9466757998616;3573.6420160339067;3600.08197909533;3658.1769101952063;4757.50046245144;5766.82699519014;5780.277465316835;5790.913136302954;5801.419858014207;5772.913406298899;5813.651180929147;5818.941810581893;5822.679752613604;5837.7373018214175;5202.687838729679;5263.526282634562;5267.341582302212;5270.446603106676;5341.866453338659;5313.8937221255555;5361.410244873061;5361.544268737162;5362.168863826113;5371.919421208685;5225.137852254374;5280.5247527722495;5295.761690723926;5303.681779093247;5334.91997620036;4460.710053846489;5250.05499010178;5293.311869456946;5298.178782288752;5323.600729945231;5157.432682404932;5163.3545806774155;5181.326427564431;5201.895962080737;5209.609279304067;5616.831495055155;5635.887282254332;5644.068728659766;5674.177244299553;5678;6273.811785646438;6283.497320214372;6312.3372045934975;6326.2092238470195;6329.715162817684;6140.995089002493;6204.875902481925;6285.057241413779;6305.180326557518;6307.4954003679595;5891;5894.204282421904;5896.498797602209;5898.8230353089475;5926.436988486087;6299.559030867887;6313.08460273267;6314.55798094129;6319.210098737658;6319.210098737658;6073.5067389466;6109.374301065143;6197.938020619827;6212.782552610637;6285.96857015712;6253.499720022387;6268.749250030039;6276.497880169575;6282.874342513124;6316.242050953823;6227.750889964442;6313.05304204373;6324.620522768557;6325.367463253638;6335.366463353628;6142.7136481981925;6213.440790328892;6233.2208473940755;6332.271788744353;6338.30278669346;4985.351904252424;5015.799368025311;5021.824236151717;5025.221090730943;5036.445990940993;4482.39487669167;4514.277715565559;4528.50186479487;4528.796204170821;4571.519990401027;5871.442212989759;5893.3222679391565;5965.194698715704;5997.790077347272;6008.279006519158;6276.246850377984;6288.151099601495;6306.148669929501;6314.052892066226;6320.083587879732;5912.290525137011;5933.881322373529;5962.910556341659;5970;5997.880042399128;5374.220737992969;5469.808556700496;5501.14732216507;5508.338999320107;5545;6268.341824108403;6303.810885673437;6319.652419116993;6328.240611126695;6345.397187267202;6221.3156552778255;6244.937550624617;6278.4035516625845;6285.340039295991;6306.0225665022;6324.525660575568;6344.492440604833;6361.93638063632;6373.553851230369;6380.521460890544;6285.214348206474;6305.936940630535;6306.747730090745;6316.747330106744;6329.841753956188;6203.968980155251;6209.472194863307;6280.309165991828;6293.118963345227;6303.810885673437;6175.44421002112;6223.657698826608;6224;6237.594556353823;6241.21984751906;5999.850003749942;6124.356942521149;6150.415710507495;6219.440250377488;6225.221847269091;5898.705064746659;6045.909311360245;6096.237970253718;6101.938980610137;6121.357257488078;6109.480694141048;6121.910283036266;6175.228096487939;6237.4698150657705;6248.9062664059165;6057.939420605738;6079.969600152148;6103.176071230327;6111.144439778345;6121.755129794937 23:25:07 INFO - PROCESS | 1664 | |1;ext.html;527.8759491519489;545.6589631480325;557.7127779193715;563.574501251555;639.8834034609607;23852.403689907762;30941.535876961836;39279.446835955714;39381.60618393815;39480.40779388312;38209.662661806775;42218.7889060555;42751.57248427515;42829.57170428295;42928;9394.436333819958;9934.900650993506;9938.304318697694;9944.40333579984;9957.45234012129;9221.35450518464;9374.812503749921;9378.390404623678;11853.940730296337;12045.759084818299;11735.589254376098;11774.764504709901;11800.46897889597;11813.409329533531;11822.7635447291;11653.359065251409;11739.295642261492;11747.94126029373;11749.88250117505;11750.70623234426;11569.190156689036;11678.88321116795;11688.064954803594;11692.88307116935;11695.883041169565;39467.407988880186;39984.80045598637;42475;42503.7874810627;42538.78730606358;3242.886498972525;3288.4574045282498;3491.14466955595;3583.62371950944;3874.070223146453;29134.708652913625;29157.562636560255;29450.410991780045;30611.387772244652;30677.926272580353;2766.986165069182;2794.902178423745;2795.175423250146;2866.584345269924;2896.681365049843;498937;502435;502662;502668.99999999633;503222;37072.629273707455;37582.62417375846;37657.81171094127;37739.81130094387;65218.347816522175;36335.81832090823;36357.27285454329;36552.81723591365;36559.26881462356;36583.08542286411;16153.273102710406;16298.10360430164;16318.673626527641;16339.26473308704;16386.508404747638;13002.08985371015;13815.309234538232;13874.098183618033;13882;13896.027278090638;8019.117897031322;8486.872696909551;8539.914600854036;8644.265237454887;8645.783855403539;3514.4552594347915;3569.3396721606587;3573.070082532299;3576.3741345264993;3598.7300952428673;978.4765150644371;1020.1430798129459;1054.093479607537;1082.242430298794;1163.3950345820126;27808.86095569509;27830.582541261898;30597.08208753741;30666.386672266428;31084.378312433626;1170.3153655111855;1174.7767924094394;1177.6937996120898;1193.0992100963717;1204.3677069538562;15669.608259793597;15943.282552285174;15974.281157347947;16262.593435164215;16320.510384688478;566118;574745.1262743743;574964;579432;581949;64039;64327.391815204996;64328.356716433176;64493;64628;1447.8117844680123;1466.413434626158;1483.5475180070182;1575.9212039397755;1583.6595132046666;28720.71279287264;29654.258643534085;29711.554326684683;30001.84999075078;30305;48749.512504874496;49009;49040.50959490502;49053.754731226116;49182.508174919225;322020.389898049;331860;332136;332352;332511;20670.276540321018;27644.72355276502;27709;28170.71829281763;28496.85751571229;43424;43541.12917741691;43692;43907.78046109749;44733.77633111813;36881.81559092188;37623.81188094042;38246;38344.808275959556;39204.01989950274;36798.44802328021;37574.43638345372;37966.050848729006;38238.23523529458;38329.658461953695;36902.8154859224;37568.060798480255;38200;38535.03662408462;38684;28258;32751.672483275815;33311.83344083361;33830.830845845616;33909.66090339065;25194.874025629753;25415.723261009967;25493.235202943943;25742.742572574036;25863.0947916822 23:25:08 INFO - PROCESS | 1664 | |2;jquery.html;420.1701639262456;421.32377534057855;421.40160971420585;421.9852305169318;433.14237809137904;20632.381028569147;20661.276855310047;21354.57290854184;22221.777782222172;22329.44176395591;21973.67039494409;21991.890040549773;22074.779252207474;22303.442413939658;22327.441813954658;5997.340292567803;6241.28225254097;6290.465310448617;6303.212098487689;6305.653189074599;4551.795169217393;4611.3544103825325;4709.387779588648;6146.784862529812;6300.086487459339;6972.163340399159;6996.7201311947465;6997.055397521321;7000.33496817804;7003.859922801566;6659.200895892475;6941.167059952812;6969.303069693041;6997.545159564663;7004.859902801915;4053.43251944728;5760.481556659921;6120.234970628671;6149.661768602725;6158.692065396713;26828.329291767666;27291.863540682367;27516.17451476459;27623;27701.584476232874;1918.3573502876552;2262.762409946965;2553.4254792671686;2568.3579105223694;2859.699731528201;9806.117449429514;14788.40846366144;15095.773563396559;15224.086554806747;16440.013599184087;1795.9102044897834;1823.0611235213876;1847.1503108570073;1861.5811442425481;1862.701967685164;72432;87821;92221;96353;100033;11601.245919015237;11667.183297169116;11784.469698863571;11836.349000805038;11880.477014618666;12991.830735233874;15123.697526049418;15378.1542015188;15391.538253852403;15431.459898903484;9042.86435703465;9083.36416450842;9089.81820363589;9101.863472047924;9184.63261469547;7330.230325815713;9133.360664753403;9137.634494620273;9176.632934682671;9200.30997675177;4764.190087685102;4787.521247875185;4796.448408433075;4800.967791924753;4841.15279826029;1834.477174005402;1974.5853370792008;2013.8552441649597;2031.1875249900158;2060.093558834108;956.2302346611045;961.5192403798101;979.9951000244953;990.6978371596732;1063.1441689440076;15221.31504082319;17438.302467901394;20214.898925505277;20529.89735051345;20681.89659051695;983.4885859353096;1011.0647650923014;1037.2376303416982;1075.1936047964027;1110.1895616200059;10533.420661863518;10899.618513351994;10903.110047846763;10994.175436842108;11008.84407137239;629361;631903;633669;641344;641771;82337.58831205805;93146.53426732823;126443;132930.3353483246;133537;1015.1624909449733;1034.8809886862957;1041.5156952017348;1100.4552746390589;1108.0913650806262;14616.926915365355;14784.63038424048;14997.700045999238;16598.997369335768;17860.374886879417;52060.47939520556;52881.7355913218;53229.73385133049;54636;56818.43181568132;78562.60718696598;82740;83170.58414707887;83452;84901.57549212214;6739.865202696017;8428.536430496382;8559.015713193063;8567.74296771098;8611;9479.952600236955;12863.485460581473;12928.224306541637;13069.803952940903;13345.266010369523;10839.783204335712;11125.721856953642;11308.604198853001;11377.54489820398;11404.771904562029;11044.116470682487;11055.778884422427;11203.831942521032;11401.486933088197;11427.600033999102;11112.777744444904;11134;11389.943050284695;11438;11482.253653512495;8294.917050829414;8410.6215220314;8476.745697629081;8516.361272904682;8729.170728780755;11028.393438360967;11600.18798684084;11659.883401166218;11843.348615826213;11916.523339066687 23:25:08 INFO - PROCESS | 1664 | |3;mootools.html;1508.6907184027275;1645.481673272919;1682.966340673187;2000.2898970865342;2067.2971189795476;8338.541380224086;8566.143385661439;8569.185927336908;9039.593218305175;9188.540572971342;8880.467171969687;8916.777080572972;9037.367384283103;9049.592768325425;9099.181073703365;2523.5079159563893;2547.872606369674;2549.6557964674716;2550.5536531107073;2554.3358726731;2483.490884368709;2559.5904655255163;2566.153169454085;2566.961495577568;2573.691157061146;2529.013684662983;2554.4380236348065;2572.4469239113587;2573.5367633825904;2576.0293375567708;2519.60946053362;2548.0444833187553;2554.1060628779965;2556.769890709845;2558.616207568861;2509.2973967289004;2512.384465805871;2526.3305224115625;2527.78513826325;2560.359910022494;8898.199162075443;9038.5932633031;9200.677976270865;9277.489738064403;9282.396644218104;5581.302337207849;5700.144978253254;5748.45389687979;5757.251557297566;5763.221965034751;3100.751939844807;3634.6733442293635;3645.489631451599;3674.0447483654443;3674.706023518112;1040.4225654761603;1045.4668119259156;1071.4428497181423;1079.578964203961;1084.5824357622294;12323.198992065487;12484.375781210903;12489.000879929585;12498.312592807391;12629.873701263054;4724.196886529298;4819.783109760069;4833.607364354597;4879.097366987119;4906.975465122651;9673.758156046155;9682.515874206403;9686.370385925033;9698.806023879482;9707.66023189185;5569.637973531708;5578.470045345685;5585.497305242548;5589.245451864028;5590.385057643656;4614.653900957374;4766;5014.473480284591;5118.181091025493;5119.206522988943;3475.4960530722906;3541.9197144871064;3970.4441378207075;4022.6982976276895;4032;1260.5525038611345;1266.7593157300084;1268.8287081244098;1278.4438769135456;1280.4878048780563;890.4568213389826;945.2107490245722;948.9003654616166;956.7227750952557;1008.6923488336128;5890.823275301748;6005.39946005396;6069.878602427927;6127.6936153193055;6200.596961197508;460.3761902621966;469.83790592245623;489.3442786665885;495.9330490383824;496.81121173953665;3905.1018265798457;3906.628870257321;3967.603239676067;4045.069633984141;4081.1837632473625;11286.887131128748;11686.18196726238;11689;11704.531818727155;11707.356095414663;11238;11352.318860868374;11380.544778208945;11501.36742479155;11534.653960381202;8616.698415555551;8727.738167854974;8808.295336373074;8812.250958668457;8830.955845220733;5677.006523858226;5830.591858569859;5876.118582212703;5893.675847828411;6002.489788367863;10028.047335503115;10209.33639313472;10290.742731431475;10321.122704569896;10332.070113689804;9996.550155243178;10468.057874791306;10608.363498190134;10632.989865962722;10834.458277086273;7224.74713385029;7326.084239470067;7343.5961022142155;7416.0729908761405;7430.294122058396;8547.487150770974;8625.8706119407;8636.654533818577;8641.351898607756;8641.827163456821;7537.623118844145;7673.6546855390525;7712.113106992543;7736.613169341398;7987.281144697005;7392.22381649919;7463.962680186564;7477.88783168242;7593.506422082547;7618.085829700472;7093.716251349888;7117.181524124792;7144.607046612487;7156.928430715834;7161.39128174111;4835.323054772264;4858.05267972742;4924.532169443897;5016;5034.773435195351;4400.62594679443;4959.801607935787;4959.975200123977;5050.419201791841;5106.872328191825 23:25:08 INFO - PROCESS | 1664 | |4;prototype.html;333.1504663109072;336.1679842390085;343.471054575953;350.6878877798758;351.08016995471866;6639.76760813371;6706.497012724053;6728.562643428174;6741.730330786769;6751.696173672184;6635.668216589175;6650.800475985716;6668.933310666892;6692.598444093345;6702.463802895769;4702.529747025295;4773.594244489205;4813.7833797479025;4840.685355451902;4889.608831293488;4182.4562806835065;4768.90462190756;4884.3406140171155;4920.458749537548;4974.751262436864;4878.926816097762;4894.804207831682;4898.820025088837;4907.509249075082;4930.013997200556;4617.953820461803;4708.223143181388;4833.275008748681;4855.805767769285;4885.8778530536665;4180.623743863067;4190.245755763961;4195.244855925932;4195.727277726938;4203.936940945889;6265.592736472115;6347.650879201642;6451.645159516224;6455.9354406455805;6467.838304042437;4728.148933192024;4979.253112033188;5858.707064646793;5871.79448719297;5893.79371721992;6064.514838812928;7108.644567771591;7109.253528379498;7115.501914865961;7118.928810711878;4425.8229670813125;4758.8102974256435;4764.356811830393;4780.832670856538;4802.087603355351;7458.105027396748;7519.849603007964;7531.736389226351;7553.7356192533;7567.4648992587545;4845.9757701211975;4854.393200849894;4861.562459378673;4867.878303042381;4876.756162191948;4926.729029903318;4938.753062346868;4944.134776414113;4955.752212389366;4964.05682920244;5835.2122463466885;5858.091995740582;5862.5896187267335;5868.501177399969;5871.735771890275;6785.728570857111;7091.184513780981;7099.964500177466;7159.89260161098;7196.604186769673;1339.7320535892861;1365.4265208612408;1648.5713714434332;1649.3320205316627;1659.8340165983304;999.5102399824034;1008.969730908074;1033.6588925654517;1037.4294138223947;1068.5885933915342;5650.830475085754;6760.898586521206;6929.480288978347;6933.757318493929;6964.4428445724225;5911.940880591225;7218.60297683622;7486.326230639269;7517.02278703765;7528.623568821537;5694.715264236772;6932.965335173292;6950.270221626757;6953.721851126;6971.651417429108;3190.5214217867506;3918.941215881764;3939.3697008478507;3953.920921581552;3954.2486927483687;6659.933400666028;7568.735094271674;7815.296623303816;7817.843643127219;7833.33416659579;7560.546367217985;7616;7621.3902887768845;7626.54240745557;7648.158702542716;7791.0650721913735;7793.805154871174;7800.765977020698;7812.648430820626;7912.5648089353335;7208.7116515340895;7494.5503269803985;7501.28737769911;7510.211427800003;7529.058867641545;6361.2048493938255;7336.449766267658;7360.374368178765;7370.815729606803;7398.926010739824;7836.294733474015;7860.764177074697;7871.724489642835;7889.13219545861;7895.486793358414;4730;4732;4741.644376671695;4743.075100355398;4749.477557468745;5198.948010519997;7175.784726458214;7458.291462311072;7459.477836551389;7463.104427468521;7173.246809084971;7217.494775365884;7222.941461683395;7235;7256.455765817479;7173.5337203081635;7179.461540384596;7197.208307086112;7198.064251647462;7204.387627051759;6106.664133472524;6684.364985326386;7050.294970502909;7061.364477197077;7067.540609860343;5688.971555142363;6848.383645471732;7191.892121618075;7275.781726548212;7292.854142917218;6784.966075169593;6883.552569082994;6918.93081069203;6990;7077.398421134261 23:25:08 INFO - PROCESS | 1664 | |5;yui.html;605.5276884030455;720.6000669628354;739.8113481062328;751.7782254235003;763.6907052643679;15085.62285942852;15257.923710381447;15446.382144714198;15454.768178477332;15470.38118475261;13890.236037017985;14249.002569820092;14260.643483912881;14268.143911365325;14271.92864035681;803.0965164190286;819.901611806584;951.3578334624118;959.3811991265629;983.4541829284744;980.4754456365846;982.4252812104927;985.4924713772377;987.4618333008527;989.0307498651326;1024.3648937658625;1024.5184763161303;1024.8718910136233;1024.9641262555813;1026.091908660837;823.7075838077486;1003.874515685543;1017.7099526634911;1025.52825700178;1028.7273872423814;761.025886864814;762.3634265388407;762.9961850190714;764.067837238568;764.4495962906698;15386.538403847902;15474.613134671723;15474.76787848172;15476;15494.225288735632;12748.171368860994;13090.934545327307;13096.672583185402;13134.737305253937;13283.335833208397;10220.540075696612;10253.64112256075;10269.691909242734;10567.207459440573;10996.560137594486;3576.8569257229938;3583.6416358364218;3633.65480279373;3679.908002299937;3856.016715737469;82748.58625706894;82914;83892;85853;86833.26333473358;9354.812903741888;9383.812323753624;9420.905790942003;9482;9795.314327997114;21710.023048962834;21997.67003494949;22868.542629147658;23012.65481017786;23056.654150187762;7766.456348055582;7836.059672839296;7972.123066462685;8118.512889226666;8197.508149511048;4246.5116511600945;4248.638865696388;4257.040547761822;4326.4808223012815;4467.173572889026;3641.235340578455;3821.2739579479808;3874.49631547897;3886.1839013806853;3888.591697871739;709.4714437743913;722.8698834209893;732.1543617122232;742.4283301857539;742.5396254322246;504.3619820926531;514.2286570144782;519.9896002079937;521.8460554136539;543.630331374669;7280.599567023759;7370.373518250902;7406.481546291708;7467.066616672916;7624.771256862303;444.41337434586035;451.10680851913395;470.57885186735865;472.92433210686124;492.1952607486785;3462.7056700180765;3496.930061398758;3531.0466174133353;3618.2582570572968;3688.4324162230946;90016;90074;91086.63370049455;91405.54297228472;94250;69142;69992;71544.28455715376;72706;72916;68673.3132668667;69769;70358;70949;72354;6014.218151640259;6921.688524016331;7057.470689698191;7134.357907788324;7178.24628414009;49927.500724992286;50538.747306263234;51208.15938804679;51258.04505730736;51534.48465515447;67923.64152716819;67978.98031529432;68193.65903170452;68933;70335.64832175807;8837.381383303107;9052.411593246417;9228;9269.073092690676;9484.430934143973;9824.901750982399;9873.308868379145;10018.899811002088;10164.237682174014;10641.627543035956;8476.634916575998;11329.320240785579;11891.76216475683;11939.403029848647;12002.39988000579;11070.5571777128;11244.831327530257;11355.37545435009;11535.36555489456;11753.412329383667;10918.290311129751;11059.170562207706;11347.716307092058;11358.432078395881;11740.88259117432;8970.69902065742;9179.17387435134;9286.164245217962;9309.627614895327;9381.530923453936;8864.822703546022;9335.81328373415;9663.2532786925;9705.514724263618;9713.271504637321 23:25:08 INFO - PROCESS | 1664 | __end_tp_report 23:25:08 INFO - PROCESS | 1664 | __start_cc_report 23:25:08 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,1500 23:25:08 INFO - PROCESS | 1664 | __end_cc_report 23:25:08 INFO - PROCESS | 1664 | __startTimestamp1467872707990__endTimestamp 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | ------- Summary: start ------- 23:25:08 INFO - PROCESS | 1664 | Number of tests: 6 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | [#0] dojo.html Cycles:165 Average:5819.26 Median:6101.94 stddev:629.17 (10.3%) stddev-sans-first:603.69 23:25:08 INFO - PROCESS | 1664 | Values: 3477.8 3554.9 3573.6 3600.1 3658.2 4757.5 5766.8 5780.3 5790.9 5801.4 5772.9 5813.7 5818.9 5822.7 5837.7 5202.7 5263.5 5267.3 5270.4 5341.9 5313.9 5361.4 5361.5 5362.2 5371.9 5225.1 5280.5 5295.8 5303.7 5334.9 4460.7 5250.1 5293.3 5298.2 5323.6 5157.4 5163.4 5181.3 5201.9 5209.6 5616.8 5635.9 5644.1 5674.2 5678.0 6273.8 6283.5 6312.3 6326.2 6329.7 6141.0 6204.9 6285.1 6305.2 6307.5 5891.0 5894.2 5896.5 5898.8 5926.4 6299.6 6313.1 6314.6 6319.2 6319.2 6073.5 6109.4 6197.9 6212.8 6286.0 6253.5 6268.7 6276.5 6282.9 6316.2 6227.8 6313.1 6324.6 6325.4 6335.4 6142.7 6213.4 6233.2 6332.3 6338.3 4985.4 5015.8 5021.8 5025.2 5036.4 4482.4 4514.3 4528.5 4528.8 4571.5 5871.4 5893.3 5965.2 5997.8 6008.3 6276.2 6288.2 6306.1 6314.1 6320.1 5912.3 5933.9 5962.9 5970.0 5997.9 5374.2 5469.8 5501.1 5508.3 5545.0 6268.3 6303.8 6319.7 6328.2 6345.4 6221.3 6244.9 6278.4 6285.3 6306.0 6324.5 6344.5 6361.9 6373.6 6380.5 6285.2 6305.9 6306.7 6316.7 6329.8 6204.0 6209.5 6280.3 6293.1 6303.8 6175.4 6223.7 6224.0 6237.6 6241.2 5999.9 6124.4 6150.4 6219.4 6225.2 5898.7 6045.9 6096.2 6101.9 6121.4 6109.5 6121.9 6175.2 6237.5 6248.9 6057.9 6080.0 6103.2 6111.1 6121.8 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | [#1] ext.html Cycles:180 Average:60378.38 Median:3502.80 stddev:128185.65 (3659.5%) stddev-sans-first:128466.48 23:25:08 INFO - PROCESS | 1664 | Values: 527.9 545.7 557.7 563.6 639.9 23852.4 30941.5 39279.4 39381.6 39480.4 38209.7 42218.8 42751.6 42829.6 42928.0 9394.4 9934.9 9938.3 9944.4 9957.5 9221.4 9374.8 9378.4 11853.9 12045.8 11735.6 11774.8 11800.5 11813.4 11822.8 11653.4 11739.3 11747.9 11749.9 11750.7 11569.2 11678.9 11688.1 11692.9 11695.9 39467.4 39984.8 42475.0 42503.8 42538.8 3242.9 3288.5 3491.1 3583.6 3874.1 29134.7 29157.6 29450.4 30611.4 30677.9 2767.0 2794.9 2795.2 2866.6 2896.7 498937.0 502435.0 502662.0 502669.0 503222.0 37072.6 37582.6 37657.8 37739.8 65218.3 36335.8 36357.3 36552.8 36559.3 36583.1 16153.3 16298.1 16318.7 16339.3 16386.5 13002.1 13815.3 13874.1 13882.0 13896.0 8019.1 8486.9 8539.9 8644.3 8645.8 3514.5 3569.3 3573.1 3576.4 3598.7 978.5 1020.1 1054.1 1082.2 1163.4 27808.9 27830.6 30597.1 30666.4 31084.4 1170.3 1174.8 1177.7 1193.1 1204.4 15669.6 15943.3 15974.3 16262.6 16320.5 566118.0 574745.1 574964.0 579432.0 581949.0 64039.0 64327.4 64328.4 64493.0 64628.0 1447.8 1466.4 1483.5 1575.9 1583.7 28720.7 29654.3 29711.6 30001.8 30305.0 48749.5 49009.0 49040.5 49053.8 49182.5 322020.4 331860.0 332136.0 332352.0 332511.0 20670.3 27644.7 27709.0 28170.7 28496.9 43424.0 43541.1 43692.0 43907.8 44733.8 36881.8 37623.8 38246.0 38344.8 39204.0 36798.4 37574.4 37966.1 38238.2 38329.7 36902.8 37568.1 38200.0 38535.0 38684.0 28258.0 32751.7 33311.8 33830.8 33909.7 25194.9 25415.7 25493.2 25742.7 25863.1 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | [#2] jquery.html Cycles:180 Average:35298.30 Median:14698.34 stddev:104993.28 (714.3%) stddev-sans-first:105255.15 23:25:08 INFO - PROCESS | 1664 | Values: 420.2 421.3 421.4 422.0 433.1 20632.4 20661.3 21354.6 22221.8 22329.4 21973.7 21991.9 22074.8 22303.4 22327.4 5997.3 6241.3 6290.5 6303.2 6305.7 4551.8 4611.4 4709.4 6146.8 6300.1 6972.2 6996.7 6997.1 7000.3 7003.9 6659.2 6941.2 6969.3 6997.5 7004.9 4053.4 5760.5 6120.2 6149.7 6158.7 26828.3 27291.9 27516.2 27623.0 27701.6 1918.4 2262.8 2553.4 2568.4 2859.7 9806.1 14788.4 15095.8 15224.1 16440.0 1795.9 1823.1 1847.2 1861.6 1862.7 72432.0 87821.0 92221.0 96353.0 100033.0 11601.2 11667.2 11784.5 11836.3 11880.5 12991.8 15123.7 15378.2 15391.5 15431.5 9042.9 9083.4 9089.8 9101.9 9184.6 7330.2 9133.4 9137.6 9176.6 9200.3 4764.2 4787.5 4796.4 4801.0 4841.2 1834.5 1974.6 2013.9 2031.2 2060.1 956.2 961.5 980.0 990.7 1063.1 15221.3 17438.3 20214.9 20529.9 20681.9 983.5 1011.1 1037.2 1075.2 1110.2 10533.4 10899.6 10903.1 10994.2 11008.8 629361.0 631903.0 633669.0 641344.0 641771.0 82337.6 93146.5 126443.0 132930.3 133537.0 1015.2 1034.9 1041.5 1100.5 1108.1 14616.9 14784.6 14997.7 16599.0 17860.4 52060.5 52881.7 53229.7 54636.0 56818.4 78562.6 82740.0 83170.6 83452.0 84901.6 6739.9 8428.5 8559.0 8567.7 8611.0 9480.0 12863.5 12928.2 13069.8 13345.3 10839.8 11125.7 11308.6 11377.5 11404.8 11044.1 11055.8 11203.8 11401.5 11427.6 11112.8 11134.0 11389.9 11438.0 11482.3 8294.9 8410.6 8476.7 8516.4 8729.2 11028.4 11600.2 11659.9 11843.3 11916.5 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | [#3] mootools.html Cycles:180 Average:5897.85 Median:2684.22 stddev:3360.33 (125.2%) stddev-sans-first:3353.57 23:25:08 INFO - PROCESS | 1664 | Values: 1508.7 1645.5 1683.0 2000.3 2067.3 8338.5 8566.1 8569.2 9039.6 9188.5 8880.5 8916.8 9037.4 9049.6 9099.2 2523.5 2547.9 2549.7 2550.6 2554.3 2483.5 2559.6 2566.2 2567.0 2573.7 2529.0 2554.4 2572.4 2573.5 2576.0 2519.6 2548.0 2554.1 2556.8 2558.6 2509.3 2512.4 2526.3 2527.8 2560.4 8898.2 9038.6 9200.7 9277.5 9282.4 5581.3 5700.1 5748.5 5757.3 5763.2 3100.8 3634.7 3645.5 3674.0 3674.7 1040.4 1045.5 1071.4 1079.6 1084.6 12323.2 12484.4 12489.0 12498.3 12629.9 4724.2 4819.8 4833.6 4879.1 4907.0 9673.8 9682.5 9686.4 9698.8 9707.7 5569.6 5578.5 5585.5 5589.2 5590.4 4614.7 4766.0 5014.5 5118.2 5119.2 3475.5 3541.9 3970.4 4022.7 4032.0 1260.6 1266.8 1268.8 1278.4 1280.5 890.5 945.2 948.9 956.7 1008.7 5890.8 6005.4 6069.9 6127.7 6200.6 460.4 469.8 489.3 495.9 496.8 3905.1 3906.6 3967.6 4045.1 4081.2 11286.9 11686.2 11689.0 11704.5 11707.4 11238.0 11352.3 11380.5 11501.4 11534.7 8616.7 8727.7 8808.3 8812.3 8831.0 5677.0 5830.6 5876.1 5893.7 6002.5 10028.0 10209.3 10290.7 10321.1 10332.1 9996.6 10468.1 10608.4 10633.0 10834.5 7224.7 7326.1 7343.6 7416.1 7430.3 8547.5 8625.9 8636.7 8641.4 8641.8 7537.6 7673.7 7712.1 7736.6 7987.3 7392.2 7464.0 7477.9 7593.5 7618.1 7093.7 7117.2 7144.6 7156.9 7161.4 4835.3 4858.1 4924.5 5016.0 5034.8 4400.6 4959.8 4960.0 5050.4 5106.9 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | [#4] prototype.html Cycles:180 Average:5829.65 Median:6664.43 stddev:1887.90 (28.3%) stddev-sans-first:1847.57 23:25:08 INFO - PROCESS | 1664 | Values: 333.2 336.2 343.5 350.7 351.1 6639.8 6706.5 6728.6 6741.7 6751.7 6635.7 6650.8 6668.9 6692.6 6702.5 4702.5 4773.6 4813.8 4840.7 4889.6 4182.5 4768.9 4884.3 4920.5 4974.8 4878.9 4894.8 4898.8 4907.5 4930.0 4618.0 4708.2 4833.3 4855.8 4885.9 4180.6 4190.2 4195.2 4195.7 4203.9 6265.6 6347.7 6451.6 6455.9 6467.8 4728.1 4979.3 5858.7 5871.8 5893.8 6064.5 7108.6 7109.3 7115.5 7118.9 4425.8 4758.8 4764.4 4780.8 4802.1 7458.1 7519.8 7531.7 7553.7 7567.5 4846.0 4854.4 4861.6 4867.9 4876.8 4926.7 4938.8 4944.1 4955.8 4964.1 5835.2 5858.1 5862.6 5868.5 5871.7 6785.7 7091.2 7100.0 7159.9 7196.6 1339.7 1365.4 1648.6 1649.3 1659.8 999.5 1009.0 1033.7 1037.4 1068.6 5650.8 6760.9 6929.5 6933.8 6964.4 5911.9 7218.6 7486.3 7517.0 7528.6 5694.7 6933.0 6950.3 6953.7 6971.7 3190.5 3918.9 3939.4 3953.9 3954.2 6659.9 7568.7 7815.3 7817.8 7833.3 7560.5 7616.0 7621.4 7626.5 7648.2 7791.1 7793.8 7800.8 7812.6 7912.6 7208.7 7494.6 7501.3 7510.2 7529.1 6361.2 7336.4 7360.4 7370.8 7398.9 7836.3 7860.8 7871.7 7889.1 7895.5 4730.0 4732.0 4741.6 4743.1 4749.5 5198.9 7175.8 7458.3 7459.5 7463.1 7173.2 7217.5 7222.9 7235.0 7256.5 7173.5 7179.5 7197.2 7198.1 7204.4 6106.7 6684.4 7050.3 7061.4 7067.5 5689.0 6848.4 7191.9 7275.8 7292.9 6785.0 6883.6 6918.9 6990.0 7077.4 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - PROCESS | 1664 | [#5] yui.html Cycles:180 Average:18169.00 Median:3278.92 stddev:25608.70 (781.0%) stddev-sans-first:25646.58 23:25:08 INFO - PROCESS | 1664 | Values: 605.5 720.6 739.8 751.8 763.7 15085.6 15257.9 15446.4 15454.8 15470.4 13890.2 14249.0 14260.6 14268.1 14271.9 803.1 819.9 951.4 959.4 983.5 980.5 982.4 985.5 987.5 989.0 1024.4 1024.5 1024.9 1025.0 1026.1 823.7 1003.9 1017.7 1025.5 1028.7 761.0 762.4 763.0 764.1 764.4 15386.5 15474.6 15474.8 15476.0 15494.2 12748.2 13090.9 13096.7 13134.7 13283.3 10220.5 10253.6 10269.7 10567.2 10996.6 3576.9 3583.6 3633.7 3679.9 3856.0 82748.6 82914.0 83892.0 85853.0 86833.3 9354.8 9383.8 9420.9 9482.0 9795.3 21710.0 21997.7 22868.5 23012.7 23056.7 7766.5 7836.1 7972.1 8118.5 8197.5 4246.5 4248.6 4257.0 4326.5 4467.2 3641.2 3821.3 3874.5 3886.2 3888.6 709.5 722.9 732.2 742.4 742.5 504.4 514.2 520.0 521.8 543.6 7280.6 7370.4 7406.5 7467.1 7624.8 444.4 451.1 470.6 472.9 492.2 3462.7 3496.9 3531.0 3618.3 3688.4 90016.0 90074.0 91086.6 91405.5 94250.0 69142.0 69992.0 71544.3 72706.0 72916.0 68673.3 69769.0 70358.0 70949.0 72354.0 6014.2 6921.7 7057.5 7134.4 7178.2 49927.5 50538.7 51208.2 51258.0 51534.5 67923.6 67979.0 68193.7 68933.0 70335.6 8837.4 9052.4 9228.0 9269.1 9484.4 9824.9 9873.3 10018.9 10164.2 10641.6 8476.6 11329.3 11891.8 11939.4 12002.4 11070.6 11244.8 11355.4 11535.4 11753.4 10918.3 11059.2 11347.7 11358.4 11740.9 8970.7 9179.2 9286.2 9309.6 9381.5 8864.8 9335.8 9663.3 9705.5 9713.3 23:25:08 INFO - PROCESS | 1664 | -------- Summary: end -------- 23:25:08 INFO - PROCESS | 1664 | 23:25:08 INFO - TEST-INFO | 1664: exit 0 23:25:08 INFO - TEST-OK | dromaeo_css | took 1113283ms 23:25:08 INFO - TEST-START | kraken 23:25:08 INFO - operating with platform_type : mac_ 23:25:08 INFO - Initialising browser for kraken test... 23:25:08 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpNZWsUp/profile http://localhost:49274/getInfo.html) 23:25:10 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 23:25:10 INFO - PROCESS | 1670 | colorDepth:24 23:25:10 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 23:25:10 INFO - PROCESS | 1670 | __metrics 23:25:10 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" 23:25:10 ERROR - PROCESS | 1670 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 23:25:11 INFO - TEST-INFO | 1670: exit 0 23:25:11 INFO - Browser initialized. 23:25:11 INFO - Running cycle 1/1 for kraken test... 23:25:11 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/T/tmpNZWsUp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 23:25:23 INFO - PROCESS | 1673 | RSS: Main: 162275328 23:25:23 INFO - PROCESS | 1673 | 23:25:25 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 23:25:26 INFO - PROCESS | 1673 | RSS: Main: 224108544 23:25:26 INFO - PROCESS | 1673 | 23:25:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 23:25:30 INFO - PROCESS | 1673 | RSS: Main: 239960064 23:25:30 INFO - PROCESS | 1673 | 23:25:33 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 23:25:34 INFO - PROCESS | 1673 | RSS: Main: 329170944 23:25:34 INFO - PROCESS | 1673 | 23:25:35 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 23:25:36 INFO - PROCESS | 1673 | RSS: Main: 232849408 23:25:36 INFO - PROCESS | 1673 | 23:25:38 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 23:25:39 INFO - PROCESS | 1673 | RSS: Main: 233947136 23:25:39 INFO - PROCESS | 1673 | 23:25:42 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 23:25:42 INFO - PROCESS | 1673 | RSS: Main: 270946304 23:25:42 INFO - PROCESS | 1673 | 23:25:45 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 23:25:46 INFO - PROCESS | 1673 | RSS: Main: 272601088 23:25:46 INFO - PROCESS | 1673 | 23:25:49 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 23:25:49 INFO - PROCESS | 1673 | RSS: Main: 270888960 23:25:49 INFO - PROCESS | 1673 | 23:25:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 23:25:52 INFO - PROCESS | 1673 | RSS: Main: 260960256 23:25:52 INFO - PROCESS | 1673 | 23:25:53 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 23:25:54 INFO - PROCESS | 1673 | RSS: Main: 268382208 23:25:54 INFO - PROCESS | 1673 | 23:25:56 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 23:25:57 INFO - PROCESS | 1673 | RSS: Main: 271310848 23:25:57 INFO - PROCESS | 1673 | 23:26:00 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 23:26:00 INFO - PROCESS | 1673 | RSS: Main: 278413312 23:26:00 INFO - PROCESS | 1673 | 23:26:03 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 23:26:04 INFO - PROCESS | 1673 | RSS: Main: 275206144 23:26:04 INFO - PROCESS | 1673 | 23:26:05 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 23:26:06 INFO - PROCESS | 1673 | RSS: Main: 336596992 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | __start_tp_report 23:26:06 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 23:26:06 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 23:26:06 INFO - PROCESS | 1673 | |i|pagename|runs| 23:26:06 INFO - PROCESS | 1673 | |0;ai-astar;114;113;120;112;113;116;157;113;113;132 23:26:06 INFO - PROCESS | 1673 | |1;audio-beat-detection;155;119;142;165;133;163;162;146;165;118 23:26:06 INFO - PROCESS | 1673 | |2;audio-dft;185;185;183;194;256;203;185;186;182;185 23:26:06 INFO - PROCESS | 1673 | |3;audio-fft;69;78;75;149;56;55;56;65;126;55 23:26:06 INFO - PROCESS | 1673 | |4;audio-oscillator;91;91;149;78;78;80;127;78;77;128 23:26:06 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;87;71;128;128;109;84;88;84;90;129 23:26:06 INFO - PROCESS | 1673 | |6;imaging-darkroom;92;92;93;93;92;107;126;144;147;147 23:26:06 INFO - PROCESS | 1673 | |7;imaging-desaturate;83;87;86;96;138;85;85;83;85;86 23:26:06 INFO - PROCESS | 1673 | |8;json-parse-financial;57;61;61;62;62;64;63;78;55;56 23:26:06 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;61;64;63;77;74;77;92;94;93;66 23:26:06 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;79;79;79;78;79;80;81;79;79;83 23:26:06 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;143;138;135;128;124;122;125;122;146;138 23:26:06 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;164;164;208;203;167;184;190;161;168;162 23:26:06 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;66;66;67;67;66;67;67;64;62 23:26:06 INFO - PROCESS | 1673 | __end_tp_report 23:26:06 INFO - PROCESS | 1673 | __start_cc_report 23:26:06 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,1891 23:26:06 INFO - PROCESS | 1673 | __end_cc_report 23:26:06 INFO - PROCESS | 1673 | __startTimestamp1467872766181__endTimestamp 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | ------- Summary: start ------- 23:26:06 INFO - PROCESS | 1673 | Number of tests: 14 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:120.30 Median:115.00 stddev:14.24 (12.4%) stddev-sans-first:14.92 23:26:06 INFO - PROCESS | 1673 | Values: 114.0 113.0 120.0 112.0 113.0 116.0 157.0 113.0 113.0 132.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:146.80 Median:158.50 stddev:18.38 (11.6%) stddev-sans-first:19.25 23:26:06 INFO - PROCESS | 1673 | Values: 155.0 119.0 142.0 165.0 133.0 163.0 162.0 146.0 165.0 118.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:194.40 Median:185.50 stddev:22.55 (12.2%) stddev-sans-first:23.66 23:26:06 INFO - PROCESS | 1673 | Values: 185.0 185.0 183.0 194.0 256.0 203.0 185.0 186.0 182.0 185.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:78.40 Median:60.50 stddev:32.71 (54.1%) stddev-sans-first:34.51 23:26:06 INFO - PROCESS | 1673 | Values: 69.0 78.0 75.0 149.0 56.0 55.0 56.0 65.0 126.0 55.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:78.00 stddev:26.68 (34.2%) stddev-sans-first:28.18 23:26:06 INFO - PROCESS | 1673 | Values: 91.0 91.0 149.0 78.0 78.0 80.0 127.0 78.0 77.0 128.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:99.80 Median:84.00 stddev:21.74 (25.9%) stddev-sans-first:22.57 23:26:06 INFO - PROCESS | 1673 | Values: 87.0 71.0 128.0 128.0 109.0 84.0 88.0 84.0 90.0 129.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:113.30 Median:92.00 stddev:24.94 (27.1%) stddev-sans-first:25.24 23:26:06 INFO - PROCESS | 1673 | Values: 92.0 92.0 93.0 93.0 92.0 107.0 126.0 144.0 147.0 147.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:91.40 Median:85.50 stddev:16.78 (19.6%) stddev-sans-first:17.52 23:26:06 INFO - PROCESS | 1673 | Values: 83.0 87.0 86.0 96.0 138.0 85.0 85.0 83.0 85.0 86.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:61.90 Median:62.00 stddev:6.44 (10.4%) stddev-sans-first:6.58 23:26:06 INFO - PROCESS | 1673 | Values: 57.0 61.0 61.0 62.0 62.0 64.0 63.0 78.0 55.0 56.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:76.10 Median:77.00 stddev:12.97 (16.8%) stddev-sans-first:12.55 23:26:06 INFO - PROCESS | 1673 | Values: 61.0 64.0 63.0 77.0 74.0 77.0 92.0 94.0 93.0 66.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:79.60 Median:79.00 stddev:1.43 (1.8%) stddev-sans-first:1.50 23:26:06 INFO - PROCESS | 1673 | Values: 79.0 79.0 79.0 78.0 79.0 80.0 81.0 79.0 79.0 83.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:132.10 Median:136.50 stddev:8.99 (6.6%) stddev-sans-first:8.62 23:26:06 INFO - PROCESS | 1673 | Values: 143.0 138.0 135.0 128.0 124.0 122.0 125.0 122.0 146.0 138.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.10 Median:176.00 stddev:17.81 (10.1%) stddev-sans-first:18.25 23:26:06 INFO - PROCESS | 1673 | Values: 164.0 164.0 208.0 203.0 167.0 184.0 190.0 161.0 168.0 162.0 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.50 stddev:1.62 (2.4%) stddev-sans-first:1.72 23:26:06 INFO - PROCESS | 1673 | Values: 66.0 66.0 66.0 67.0 67.0 66.0 67.0 67.0 64.0 62.0 23:26:06 INFO - PROCESS | 1673 | -------- Summary: end -------- 23:26:06 INFO - PROCESS | 1673 | 23:26:06 INFO - TEST-INFO | 1673: exit 0 23:26:06 INFO - TEST-OK | kraken | took 58435ms 23:26:06 INFO - SUITE-END | took 1171s 23:26:07 INFO - Completed test suite (00:19:32) 23:26:07 INFO - javascript benchmark 23:26:07 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3477.7565570410075, 3554.9466757998616, 3573.6420160339067, 3600.08197909533, 3658.1769101952063, 4757.50046245144, 5766.82699519014, 5780.277465316835, 5790.913136302954, 5801.419858014207, 5772.913406298899, 5813.651180929147, 5818.941810581893, 5822.679752613604, 5837.7373018214175, 5202.687838729679, 5263.526282634562, 5267.341582302212, 5270.446603106676, 5341.866453338659, 5313.8937221255555, 5361.410244873061, 5361.544268737162, 5362.168863826113, 5371.919421208685, 5225.137852254374, 5280.5247527722495, 5295.761690723926, 5303.681779093247, 5334.91997620036, 4460.710053846489, 5250.05499010178, 5293.311869456946, 5298.178782288752, 5323.600729945231, 5157.432682404932, 5163.3545806774155, 5181.326427564431, 5201.895962080737, 5209.609279304067, 5616.831495055155, 5635.887282254332, 5644.068728659766, 5674.177244299553, 5678.0, 6273.811785646438, 6283.497320214372, 6312.3372045934975, 6326.2092238470195, 6329.715162817684, 6140.995089002493, 6204.875902481925, 6285.057241413779, 6305.180326557518, 6307.4954003679595, 5891.0, 5894.204282421904, 5896.498797602209, 5898.8230353089475, 5926.436988486087, 6299.559030867887, 6313.08460273267, 6314.55798094129, 6319.210098737658, 6319.210098737658, 6073.5067389466, 6109.374301065143, 6197.938020619827, 6212.782552610637, 6285.96857015712, 6253.499720022387, 6268.749250030039, 6276.497880169575, 6282.874342513124, 6316.242050953823, 6227.750889964442, 6313.05304204373, 6324.620522768557, 6325.367463253638, 6335.366463353628, 6142.7136481981925, 6213.440790328892, 6233.2208473940755, 6332.271788744353, 6338.30278669346, 4985.351904252424, 5015.799368025311, 5021.824236151717, 5025.221090730943, 5036.445990940993, 4482.39487669167, 4514.277715565559, 4528.50186479487, 4528.796204170821, 4571.519990401027, 5871.442212989759, 5893.3222679391565, 5965.194698715704, 5997.790077347272, 6008.279006519158, 6276.246850377984, 6288.151099601495, 6306.148669929501, 6314.052892066226, 6320.083587879732, 5912.290525137011, 5933.881322373529, 5962.910556341659, 5970.0, 5997.880042399128, 5374.220737992969, 5469.808556700496, 5501.14732216507, 5508.338999320107, 5545.0, 6268.341824108403, 6303.810885673437, 6319.652419116993, 6328.240611126695, 6345.397187267202, 6221.3156552778255, 6244.937550624617, 6278.4035516625845, 6285.340039295991, 6306.0225665022, 6324.525660575568, 6344.492440604833, 6361.93638063632, 6373.553851230369, 6380.521460890544, 6285.214348206474, 6305.936940630535, 6306.747730090745, 6316.747330106744, 6329.841753956188, 6203.968980155251, 6209.472194863307, 6280.309165991828, 6293.118963345227, 6303.810885673437, 6175.44421002112, 6223.657698826608, 6224.0, 6237.594556353823, 6241.21984751906, 5999.850003749942, 6124.356942521149, 6150.415710507495, 6219.440250377488, 6225.221847269091, 5898.705064746659, 6045.909311360245, 6096.237970253718, 6101.938980610137, 6121.357257488078, 6109.480694141048, 6121.910283036266, 6175.228096487939, 6237.4698150657705, 6248.9062664059165, 6057.939420605738, 6079.969600152148, 6103.176071230327, 6111.144439778345, 6121.755129794937], "value": 5780.794441890622, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [527.8759491519489, 545.6589631480325, 557.7127779193715, 563.574501251555, 639.8834034609607, 23852.403689907762, 30941.535876961836, 39279.446835955714, 39381.60618393815, 39480.40779388312, 38209.662661806775, 42218.7889060555, 42751.57248427515, 42829.57170428295, 42928.0, 9394.436333819958, 9934.900650993506, 9938.304318697694, 9944.40333579984, 9957.45234012129, 9221.35450518464, 9374.812503749921, 9378.390404623678, 11853.940730296337, 12045.759084818299, 11735.589254376098, 11774.764504709901, 11800.46897889597, 11813.409329533531, 11822.7635447291, 11653.359065251409, 11739.295642261492, 11747.94126029373, 11749.88250117505, 11750.70623234426, 11569.190156689036, 11678.88321116795, 11688.064954803594, 11692.88307116935, 11695.883041169565, 39467.407988880186, 39984.80045598637, 42475.0, 42503.7874810627, 42538.78730606358, 3242.886498972525, 3288.4574045282498, 3491.14466955595, 3583.62371950944, 3874.070223146453, 29134.708652913625, 29157.562636560255, 29450.410991780045, 30611.387772244652, 30677.926272580353, 2766.986165069182, 2794.902178423745, 2795.175423250146, 2866.584345269924, 2896.681365049843, 498937.0, 502435.0, 502662.0, 502668.99999999633, 503222.0, 37072.629273707455, 37582.62417375846, 37657.81171094127, 37739.81130094387, 65218.347816522175, 36335.81832090823, 36357.27285454329, 36552.81723591365, 36559.26881462356, 36583.08542286411, 16153.273102710406, 16298.10360430164, 16318.673626527641, 16339.26473308704, 16386.508404747638, 13002.08985371015, 13815.309234538232, 13874.098183618033, 13882.0, 13896.027278090638, 8019.117897031322, 8486.872696909551, 8539.914600854036, 8644.265237454887, 8645.783855403539, 3514.4552594347915, 3569.3396721606587, 3573.070082532299, 3576.3741345264993, 3598.7300952428673, 978.4765150644371, 1020.1430798129459, 1054.093479607537, 1082.242430298794, 1163.3950345820126, 27808.86095569509, 27830.582541261898, 30597.08208753741, 30666.386672266428, 31084.378312433626, 1170.3153655111855, 1174.7767924094394, 1177.6937996120898, 1193.0992100963717, 1204.3677069538562, 15669.608259793597, 15943.282552285174, 15974.281157347947, 16262.593435164215, 16320.510384688478, 566118.0, 574745.1262743743, 574964.0, 579432.0, 581949.0, 64039.0, 64327.391815204996, 64328.356716433176, 64493.0, 64628.0, 1447.8117844680123, 1466.413434626158, 1483.5475180070182, 1575.9212039397755, 1583.6595132046666, 28720.71279287264, 29654.258643534085, 29711.554326684683, 30001.84999075078, 30305.0, 48749.512504874496, 49009.0, 49040.50959490502, 49053.754731226116, 49182.508174919225, 322020.389898049, 331860.0, 332136.0, 332352.0, 332511.0, 20670.276540321018, 27644.72355276502, 27709.0, 28170.71829281763, 28496.85751571229, 43424.0, 43541.12917741691, 43692.0, 43907.78046109749, 44733.77633111813, 36881.81559092188, 37623.81188094042, 38246.0, 38344.808275959556, 39204.01989950274, 36798.44802328021, 37574.43638345372, 37966.050848729006, 38238.23523529458, 38329.658461953695, 36902.8154859224, 37568.060798480255, 38200.0, 38535.03662408462, 38684.0, 28258.0, 32751.672483275815, 33311.83344083361, 33830.830845845616, 33909.66090339065, 25194.874025629753, 25415.723261009967, 25493.235202943943, 25742.742572574036, 25863.0947916822], "value": 18798.404299220165, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [420.1701639262456, 421.32377534057855, 421.40160971420585, 421.9852305169318, 433.14237809137904, 20632.381028569147, 20661.276855310047, 21354.57290854184, 22221.777782222172, 22329.44176395591, 21973.67039494409, 21991.890040549773, 22074.779252207474, 22303.442413939658, 22327.441813954658, 5997.340292567803, 6241.28225254097, 6290.465310448617, 6303.212098487689, 6305.653189074599, 4551.795169217393, 4611.3544103825325, 4709.387779588648, 6146.784862529812, 6300.086487459339, 6972.163340399159, 6996.7201311947465, 6997.055397521321, 7000.33496817804, 7003.859922801566, 6659.200895892475, 6941.167059952812, 6969.303069693041, 6997.545159564663, 7004.859902801915, 4053.43251944728, 5760.481556659921, 6120.234970628671, 6149.661768602725, 6158.692065396713, 26828.329291767666, 27291.863540682367, 27516.17451476459, 27623.0, 27701.584476232874, 1918.3573502876552, 2262.762409946965, 2553.4254792671686, 2568.3579105223694, 2859.699731528201, 9806.117449429514, 14788.40846366144, 15095.773563396559, 15224.086554806747, 16440.013599184087, 1795.9102044897834, 1823.0611235213876, 1847.1503108570073, 1861.5811442425481, 1862.701967685164, 72432.0, 87821.0, 92221.0, 96353.0, 100033.0, 11601.245919015237, 11667.183297169116, 11784.469698863571, 11836.349000805038, 11880.477014618666, 12991.830735233874, 15123.697526049418, 15378.1542015188, 15391.538253852403, 15431.459898903484, 9042.86435703465, 9083.36416450842, 9089.81820363589, 9101.863472047924, 9184.63261469547, 7330.230325815713, 9133.360664753403, 9137.634494620273, 9176.632934682671, 9200.30997675177, 4764.190087685102, 4787.521247875185, 4796.448408433075, 4800.967791924753, 4841.15279826029, 1834.477174005402, 1974.5853370792008, 2013.8552441649597, 2031.1875249900158, 2060.093558834108, 956.2302346611045, 961.5192403798101, 979.9951000244953, 990.6978371596732, 1063.1441689440076, 15221.31504082319, 17438.302467901394, 20214.898925505277, 20529.89735051345, 20681.89659051695, 983.4885859353096, 1011.0647650923014, 1037.2376303416982, 1075.1936047964027, 1110.1895616200059, 10533.420661863518, 10899.618513351994, 10903.110047846763, 10994.175436842108, 11008.84407137239, 629361.0, 631903.0, 633669.0, 641344.0, 641771.0, 82337.58831205805, 93146.53426732823, 126443.0, 132930.3353483246, 133537.0, 1015.1624909449733, 1034.8809886862957, 1041.5156952017348, 1100.4552746390589, 1108.0913650806262, 14616.926915365355, 14784.63038424048, 14997.700045999238, 16598.997369335768, 17860.374886879417, 52060.47939520556, 52881.7355913218, 53229.73385133049, 54636.0, 56818.43181568132, 78562.60718696598, 82740.0, 83170.58414707887, 83452.0, 84901.57549212214, 6739.865202696017, 8428.536430496382, 8559.015713193063, 8567.74296771098, 8611.0, 9479.952600236955, 12863.485460581473, 12928.224306541637, 13069.803952940903, 13345.266010369523, 10839.783204335712, 11125.721856953642, 11308.604198853001, 11377.54489820398, 11404.771904562029, 11044.116470682487, 11055.778884422427, 11203.831942521032, 11401.486933088197, 11427.600033999102, 11112.777744444904, 11134.0, 11389.943050284695, 11438.0, 11482.253653512495, 8294.917050829414, 8410.6215220314, 8476.745697629081, 8516.361272904682, 8729.170728780755, 11028.393438360967, 11600.18798684084, 11659.883401166218, 11843.348615826213, 11916.523339066687], "value": 9862.734458899076, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1508.6907184027275, 1645.481673272919, 1682.966340673187, 2000.2898970865342, 2067.2971189795476, 8338.541380224086, 8566.143385661439, 8569.185927336908, 9039.593218305175, 9188.540572971342, 8880.467171969687, 8916.777080572972, 9037.367384283103, 9049.592768325425, 9099.181073703365, 2523.5079159563893, 2547.872606369674, 2549.6557964674716, 2550.5536531107073, 2554.3358726731, 2483.490884368709, 2559.5904655255163, 2566.153169454085, 2566.961495577568, 2573.691157061146, 2529.013684662983, 2554.4380236348065, 2572.4469239113587, 2573.5367633825904, 2576.0293375567708, 2519.60946053362, 2548.0444833187553, 2554.1060628779965, 2556.769890709845, 2558.616207568861, 2509.2973967289004, 2512.384465805871, 2526.3305224115625, 2527.78513826325, 2560.359910022494, 8898.199162075443, 9038.5932633031, 9200.677976270865, 9277.489738064403, 9282.396644218104, 5581.302337207849, 5700.144978253254, 5748.45389687979, 5757.251557297566, 5763.221965034751, 3100.751939844807, 3634.6733442293635, 3645.489631451599, 3674.0447483654443, 3674.706023518112, 1040.4225654761603, 1045.4668119259156, 1071.4428497181423, 1079.578964203961, 1084.5824357622294, 12323.198992065487, 12484.375781210903, 12489.000879929585, 12498.312592807391, 12629.873701263054, 4724.196886529298, 4819.783109760069, 4833.607364354597, 4879.097366987119, 4906.975465122651, 9673.758156046155, 9682.515874206403, 9686.370385925033, 9698.806023879482, 9707.66023189185, 5569.637973531708, 5578.470045345685, 5585.497305242548, 5589.245451864028, 5590.385057643656, 4614.653900957374, 4766.0, 5014.473480284591, 5118.181091025493, 5119.206522988943, 3475.4960530722906, 3541.9197144871064, 3970.4441378207075, 4022.6982976276895, 4032.0, 1260.5525038611345, 1266.7593157300084, 1268.8287081244098, 1278.4438769135456, 1280.4878048780563, 890.4568213389826, 945.2107490245722, 948.9003654616166, 956.7227750952557, 1008.6923488336128, 5890.823275301748, 6005.39946005396, 6069.878602427927, 6127.6936153193055, 6200.596961197508, 460.3761902621966, 469.83790592245623, 489.3442786665885, 495.9330490383824, 496.81121173953665, 3905.1018265798457, 3906.628870257321, 3967.603239676067, 4045.069633984141, 4081.1837632473625, 11286.887131128748, 11686.18196726238, 11689.0, 11704.531818727155, 11707.356095414663, 11238.0, 11352.318860868374, 11380.544778208945, 11501.36742479155, 11534.653960381202, 8616.698415555551, 8727.738167854974, 8808.295336373074, 8812.250958668457, 8830.955845220733, 5677.006523858226, 5830.591858569859, 5876.118582212703, 5893.675847828411, 6002.489788367863, 10028.047335503115, 10209.33639313472, 10290.742731431475, 10321.122704569896, 10332.070113689804, 9996.550155243178, 10468.057874791306, 10608.363498190134, 10632.989865962722, 10834.458277086273, 7224.74713385029, 7326.084239470067, 7343.5961022142155, 7416.0729908761405, 7430.294122058396, 8547.487150770974, 8625.8706119407, 8636.654533818577, 8641.351898607756, 8641.827163456821, 7537.623118844145, 7673.6546855390525, 7712.113106992543, 7736.613169341398, 7987.281144697005, 7392.22381649919, 7463.962680186564, 7477.88783168242, 7593.506422082547, 7618.085829700472, 7093.716251349888, 7117.181524124792, 7144.607046612487, 7156.928430715834, 7161.39128174111, 4835.323054772264, 4858.05267972742, 4924.532169443897, 5016.0, 5034.773435195351, 4400.62594679443, 4959.801607935787, 4959.975200123977, 5050.419201791841, 5106.872328191825], "value": 4662.949916360385, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [333.1504663109072, 336.1679842390085, 343.471054575953, 350.6878877798758, 351.08016995471866, 6639.76760813371, 6706.497012724053, 6728.562643428174, 6741.730330786769, 6751.696173672184, 6635.668216589175, 6650.800475985716, 6668.933310666892, 6692.598444093345, 6702.463802895769, 4702.529747025295, 4773.594244489205, 4813.7833797479025, 4840.685355451902, 4889.608831293488, 4182.4562806835065, 4768.90462190756, 4884.3406140171155, 4920.458749537548, 4974.751262436864, 4878.926816097762, 4894.804207831682, 4898.820025088837, 4907.509249075082, 4930.013997200556, 4617.953820461803, 4708.223143181388, 4833.275008748681, 4855.805767769285, 4885.8778530536665, 4180.623743863067, 4190.245755763961, 4195.244855925932, 4195.727277726938, 4203.936940945889, 6265.592736472115, 6347.650879201642, 6451.645159516224, 6455.9354406455805, 6467.838304042437, 4728.148933192024, 4979.253112033188, 5858.707064646793, 5871.79448719297, 5893.79371721992, 6064.514838812928, 7108.644567771591, 7109.253528379498, 7115.501914865961, 7118.928810711878, 4425.8229670813125, 4758.8102974256435, 4764.356811830393, 4780.832670856538, 4802.087603355351, 7458.105027396748, 7519.849603007964, 7531.736389226351, 7553.7356192533, 7567.4648992587545, 4845.9757701211975, 4854.393200849894, 4861.562459378673, 4867.878303042381, 4876.756162191948, 4926.729029903318, 4938.753062346868, 4944.134776414113, 4955.752212389366, 4964.05682920244, 5835.2122463466885, 5858.091995740582, 5862.5896187267335, 5868.501177399969, 5871.735771890275, 6785.728570857111, 7091.184513780981, 7099.964500177466, 7159.89260161098, 7196.604186769673, 1339.7320535892861, 1365.4265208612408, 1648.5713714434332, 1649.3320205316627, 1659.8340165983304, 999.5102399824034, 1008.969730908074, 1033.6588925654517, 1037.4294138223947, 1068.5885933915342, 5650.830475085754, 6760.898586521206, 6929.480288978347, 6933.757318493929, 6964.4428445724225, 5911.940880591225, 7218.60297683622, 7486.326230639269, 7517.02278703765, 7528.623568821537, 5694.715264236772, 6932.965335173292, 6950.270221626757, 6953.721851126, 6971.651417429108, 3190.5214217867506, 3918.941215881764, 3939.3697008478507, 3953.920921581552, 3954.2486927483687, 6659.933400666028, 7568.735094271674, 7815.296623303816, 7817.843643127219, 7833.33416659579, 7560.546367217985, 7616.0, 7621.3902887768845, 7626.54240745557, 7648.158702542716, 7791.0650721913735, 7793.805154871174, 7800.765977020698, 7812.648430820626, 7912.5648089353335, 7208.7116515340895, 7494.5503269803985, 7501.28737769911, 7510.211427800003, 7529.058867641545, 6361.2048493938255, 7336.449766267658, 7360.374368178765, 7370.815729606803, 7398.926010739824, 7836.294733474015, 7860.764177074697, 7871.724489642835, 7889.13219545861, 7895.486793358414, 4730.0, 4732.0, 4741.644376671695, 4743.075100355398, 4749.477557468745, 5198.948010519997, 7175.784726458214, 7458.291462311072, 7459.477836551389, 7463.104427468521, 7173.246809084971, 7217.494775365884, 7222.941461683395, 7235.0, 7256.455765817479, 7173.5337203081635, 7179.461540384596, 7197.208307086112, 7198.064251647462, 7204.387627051759, 6106.664133472524, 6684.364985326386, 7050.294970502909, 7061.364477197077, 7067.540609860343, 5688.971555142363, 6848.383645471732, 7191.892121618075, 7275.781726548212, 7292.854142917218, 6784.966075169593, 6883.552569082994, 6918.93081069203, 6990.0, 7077.398421134261], "value": 5196.286725105608, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [605.5276884030455, 720.6000669628354, 739.8113481062328, 751.7782254235003, 763.6907052643679, 15085.62285942852, 15257.923710381447, 15446.382144714198, 15454.768178477332, 15470.38118475261, 13890.236037017985, 14249.002569820092, 14260.643483912881, 14268.143911365325, 14271.92864035681, 803.0965164190286, 819.901611806584, 951.3578334624118, 959.3811991265629, 983.4541829284744, 980.4754456365846, 982.4252812104927, 985.4924713772377, 987.4618333008527, 989.0307498651326, 1024.3648937658625, 1024.5184763161303, 1024.8718910136233, 1024.9641262555813, 1026.091908660837, 823.7075838077486, 1003.874515685543, 1017.7099526634911, 1025.52825700178, 1028.7273872423814, 761.025886864814, 762.3634265388407, 762.9961850190714, 764.067837238568, 764.4495962906698, 15386.538403847902, 15474.613134671723, 15474.76787848172, 15476.0, 15494.225288735632, 12748.171368860994, 13090.934545327307, 13096.672583185402, 13134.737305253937, 13283.335833208397, 10220.540075696612, 10253.64112256075, 10269.691909242734, 10567.207459440573, 10996.560137594486, 3576.8569257229938, 3583.6416358364218, 3633.65480279373, 3679.908002299937, 3856.016715737469, 82748.58625706894, 82914.0, 83892.0, 85853.0, 86833.26333473358, 9354.812903741888, 9383.812323753624, 9420.905790942003, 9482.0, 9795.314327997114, 21710.023048962834, 21997.67003494949, 22868.542629147658, 23012.65481017786, 23056.654150187762, 7766.456348055582, 7836.059672839296, 7972.123066462685, 8118.512889226666, 8197.508149511048, 4246.5116511600945, 4248.638865696388, 4257.040547761822, 4326.4808223012815, 4467.173572889026, 3641.235340578455, 3821.2739579479808, 3874.49631547897, 3886.1839013806853, 3888.591697871739, 709.4714437743913, 722.8698834209893, 732.1543617122232, 742.4283301857539, 742.5396254322246, 504.3619820926531, 514.2286570144782, 519.9896002079937, 521.8460554136539, 543.630331374669, 7280.599567023759, 7370.373518250902, 7406.481546291708, 7467.066616672916, 7624.771256862303, 444.41337434586035, 451.10680851913395, 470.57885186735865, 472.92433210686124, 492.1952607486785, 3462.7056700180765, 3496.930061398758, 3531.0466174133353, 3618.2582570572968, 3688.4324162230946, 90016.0, 90074.0, 91086.63370049455, 91405.54297228472, 94250.0, 69142.0, 69992.0, 71544.28455715376, 72706.0, 72916.0, 68673.3132668667, 69769.0, 70358.0, 70949.0, 72354.0, 6014.218151640259, 6921.688524016331, 7057.470689698191, 7134.357907788324, 7178.24628414009, 49927.500724992286, 50538.747306263234, 51208.15938804679, 51258.04505730736, 51534.48465515447, 67923.64152716819, 67978.98031529432, 68193.65903170452, 68933.0, 70335.64832175807, 8837.381383303107, 9052.411593246417, 9228.0, 9269.073092690676, 9484.430934143973, 9824.901750982399, 9873.308868379145, 10018.899811002088, 10164.237682174014, 10641.627543035956, 8476.634916575998, 11329.320240785579, 11891.76216475683, 11939.403029848647, 12002.39988000579, 11070.5571777128, 11244.831327530257, 11355.37545435009, 11535.36555489456, 11753.412329383667, 10918.290311129751, 11059.170562207706, 11347.716307092058, 11358.432078395881, 11740.88259117432, 8970.69902065742, 9179.17387435134, 9286.164245217962, 9309.627614895327, 9381.530923453936, 8864.822703546022, 9335.81328373415, 9663.2532786925, 9705.514724263618, 9713.271504637321], "value": 6843.813275199076, "unit": "score"}], "name": "dromaeo_css", "value": 7498.34576916739, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 120.0, 112.0, 113.0, 116.0, 157.0, 113.0, 113.0, 132.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 119.0, 142.0, 165.0, 133.0, 163.0, 162.0, 146.0, 165.0, 118.0], "value": 146.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 194.0, 256.0, 203.0, 185.0, 186.0, 182.0, 185.0], "value": 194.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 75.0, 149.0, 56.0, 55.0, 56.0, 65.0, 126.0, 55.0], "value": 78.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 91.0, 149.0, 78.0, 78.0, 80.0, 127.0, 78.0, 77.0, 128.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 128.0, 128.0, 109.0, 84.0, 88.0, 84.0, 90.0, 129.0], "value": 99.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 93.0, 93.0, 92.0, 107.0, 126.0, 144.0, 147.0, 147.0], "value": 113.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 87.0, 86.0, 96.0, 138.0, 85.0, 85.0, 83.0, 85.0, 86.0], "value": 91.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 61.0, 61.0, 62.0, 62.0, 64.0, 63.0, 78.0, 55.0, 56.0], "value": 61.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 64.0, 63.0, 77.0, 74.0, 77.0, 92.0, 94.0, 93.0, 66.0], "value": 76.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 79.0, 79.0, 78.0, 79.0, 80.0, 81.0, 79.0, 79.0, 83.0], "value": 79.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 138.0, 135.0, 128.0, 124.0, 122.0, 125.0, 122.0, 146.0, 138.0], "value": 132.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 164.0, 208.0, 203.0, 167.0, 184.0, 190.0, 161.0, 168.0, 162.0], "value": 177.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 66.0, 67.0, 67.0, 66.0, 67.0, 67.0, 64.0, 62.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1534.6999999999996, "alertThreshold": 2.0}]} 23:26:07 INFO - Return code: 0 23:26:07 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 23:26:07 ERROR - # TBPL SUCCESS # 23:26:07 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 23:26:07 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 23:26:07 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 23:26:07 INFO - 2016-07-06 23:26:07.486 screenresolution[1677:9779] starting screenresolution argv=screenresolution get 23:26:07 INFO - 2016-07-06 23:26:07.488 screenresolution[1677:9779] Display 0: 1600x1200x32@60 23:26:07 INFO - 2016-07-06 23:26:07.494 screenresolution[1678:9782] starting screenresolution argv=screenresolution list 23:26:07 INFO - Available Modes on Display 0 23:26:07 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 23:26:07 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 23:26:07 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 23:26:07 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 23:26:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 23:26:07 INFO - Intel Iris: 23:26:07 INFO - Chipset Model: Intel Iris 23:26:07 INFO - Type: GPU 23:26:07 INFO - Bus: Built-In 23:26:07 INFO - VRAM (Dynamic, Max): 1536 MB 23:26:07 INFO - Vendor: Intel (0x8086) 23:26:07 INFO - Device ID: 0x0a2e 23:26:07 INFO - Revision ID: 0x0009 23:26:07 INFO - Displays: 23:26:07 INFO - Display: 23:26:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 23:26:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 23:26:07 INFO - Display Serial Number: 1600x1200 60 23:26:07 INFO - Main Display: Yes 23:26:07 INFO - Mirror: Off 23:26:07 INFO - Online: Yes 23:26:07 INFO - Rotation: Supported 23:26:07 INFO - Return code: 0 23:26:07 INFO - Running post-action listener: _resource_record_post_action 23:26:07 INFO - Running post-run listener: _resource_record_post_run 23:26:08 INFO - Total resource usage - Wall time: 1196s; CPU: 52.0%; Read bytes: 38065664; Write bytes: 493101056; Read time: 383; Write time: 1703 23:26:08 INFO - install - Wall time: 23s; CPU: 55.0%; Read bytes: 194574848; Write bytes: 184766464; Read time: 17924; Write time: 325 23:26:08 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 30944768; Write bytes: 297541632; Read time: 294; Write time: 1341 23:26:08 INFO - Running post-run listener: _upload_blobber_files 23:26:08 INFO - Blob upload gear active. 23:26:08 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 23:26:08 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 23:26:08 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'] 23:26:08 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 23:26:09 INFO - (blobuploader) - INFO - Open directory for files ... 23:26:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 23:26:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 23:26:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:10 INFO - (blobuploader) - INFO - Done attempting. 23:26:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 23:26:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 23:26:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 23:26:11 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 23:26:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 23:26:11 INFO - (blobuploader) - INFO - Done attempting. 23:26:11 INFO - (blobuploader) - INFO - Iteration through files over. 23:26:11 INFO - Return code: 0 23:26:11 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 23:26:11 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 23:26:11 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b01156c26b671160a08eb3db368af9fb3ff39f1effe9c56e96bcc02844997d560389fe1f423b0164619c9afadd15d377ec63258bc3385167a5d74d554245a91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ca4b924f071551b310622698ff7844ef2f58309ef3d362da565a84a8b0a5aac95fc8b0292070bbe76a1cd24f653fbcce8270ac35e17b5dc8523b5911811ce2"} 23:26:11 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 23:26:11 INFO - Writing to file /builds/slave/test/properties/blobber_files 23:26:11 INFO - Contents: 23:26:11 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4b01156c26b671160a08eb3db368af9fb3ff39f1effe9c56e96bcc02844997d560389fe1f423b0164619c9afadd15d377ec63258bc3385167a5d74d554245a91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ca4b924f071551b310622698ff7844ef2f58309ef3d362da565a84a8b0a5aac95fc8b0292070bbe76a1cd24f653fbcce8270ac35e17b5dc8523b5911811ce2"} 23:26:11 INFO - Running post-run listener: copy_logs_to_upload_dir 23:26:11 INFO - Copying logs to upload dir... 23:26:11 INFO - mkdir: /builds/slave/test/build/upload/logs 23:26:11 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.963258 ========= master_lag: 158.29 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 23 mins, 40 secs) (at 2016-07-06 23:28:49.458565) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-06 23:28:49.462177) ========= 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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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/4b01156c26b671160a08eb3db368af9fb3ff39f1effe9c56e96bcc02844997d560389fe1f423b0164619c9afadd15d377ec63258bc3385167a5d74d554245a91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ca4b924f071551b310622698ff7844ef2f58309ef3d362da565a84a8b0a5aac95fc8b0292070bbe76a1cd24f653fbcce8270ac35e17b5dc8523b5911811ce2"} 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.011417 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/4b01156c26b671160a08eb3db368af9fb3ff39f1effe9c56e96bcc02844997d560389fe1f423b0164619c9afadd15d377ec63258bc3385167a5d74d554245a91", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c9ca4b924f071551b310622698ff7844ef2f58309ef3d362da565a84a8b0a5aac95fc8b0292070bbe76a1cd24f653fbcce8270ac35e17b5dc8523b5911811ce2"}' ========= master_lag: 1.28 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 1 secs) (at 2016-07-06 23:28:50.748958) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 26 secs) (at 2016-07-06 23:28:50.749333) ========= 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.Gfuy18DCwS/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.7uDyiPh3yN/Listeners TMPDIR=/var/folders/wd/lr9qrw8n6cn5lsbcg99yj9x400000w/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.005261 ========= master_lag: 26.45 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 26 secs) (at 2016-07-06 23:29:17.208213) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 23:29:17.208481) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-06 23:29:17.545661) ========= ========= Total master_lag: 187.10 =========