builder: mozilla-beta_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0070 starttime: 1467996181.67 results: success (0) buildid: 20160708080626 builduid: aa0b963c60e8450ea4f7f24a6e034714 revision: b6fbb6d3fb58243dcf9a72e741a028bb7fed3540 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:01.666974) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:01.667407) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:01.667680) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:01.722751) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:01.723036) ========= 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.u1QO8zoalZ/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.k25EpZRgze/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-08 09:43:01-- 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% 184M=0s 2016-07-08 09:43:02 (184 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.545237 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:02.289960) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:02.290248) ========= 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.u1QO8zoalZ/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.k25EpZRgze/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.027222 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:02.338321) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 09:43:02.338640) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev b6fbb6d3fb58243dcf9a72e741a028bb7fed3540 --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 b6fbb6d3fb58243dcf9a72e741a028bb7fed3540 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u1QO8zoalZ/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.k25EpZRgze/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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-08 09:43:02,411 truncating revision to first 12 chars 2016-07-08 09:43:02,412 Setting DEBUG logging. 2016-07-08 09:43:02,412 attempt 1/10 2016-07-08 09:43:02,412 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/b6fbb6d3fb58?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-08 09:43:03,134 unpacking tar archive at: mozilla-beta-b6fbb6d3fb58/testing/mozharness/ program finished with exit code 0 elapsedTime=1.002958 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-07-08 09:43:03.362807) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:03.363128) ========= script_repo_revision: b6fbb6d3fb58243dcf9a72e741a028bb7fed3540 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:03.363498) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:03.363772) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-08 09:43:03.381386) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-08 09:43:03.381861) ========= /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.u1QO8zoalZ/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.k25EpZRgze/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:03 INFO - MultiFileLogger online at 20160708 09:43:03 in /builds/slave/test 09:43:03 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 09:43:03 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:43:03 INFO - {'append_to_log': False, 09:43:03 INFO - 'base_work_dir': '/builds/slave/test', 09:43:03 INFO - 'blob_upload_branch': 'Mozilla-Beta', 09:43:03 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:43:03 INFO - 'branch': 'Mozilla-Beta', 09:43:03 INFO - 'buildbot_json_path': 'buildprops.json', 09:43:03 INFO - 'config_files': ('talos/mac_config.py',), 09:43:03 INFO - 'default_actions': ('clobber', 09:43:03 INFO - 'read-buildbot-config', 09:43:03 INFO - 'download-and-extract', 09:43:03 INFO - 'populate-webroot', 09:43:03 INFO - 'create-virtualenv', 09:43:03 INFO - 'install', 09:43:03 INFO - 'run-tests'), 09:43:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:43:03 INFO - 'download_minidump_stackwalk': True, 09:43:03 INFO - 'download_symbols': 'ondemand', 09:43:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:43:03 INFO - 'tooltool.py': '/tools/tooltool.py', 09:43:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:43:03 INFO - '/tools/misc-python/virtualenv.py')}, 09:43:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:43:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:43:03 INFO - 'installer_path': 'installer.exe', 09:43:03 INFO - 'log_level': 'info', 09:43:03 INFO - 'log_name': 'talos', 09:43:03 INFO - 'log_to_console': True, 09:43:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:43:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:43:03 INFO - 'opt_config_files': (), 09:43:03 INFO - 'pip_index': False, 09:43:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:03 INFO - 'cmd': ('bash', 09:43:03 INFO - '-c', 09:43:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:03 INFO - 'enabled': True, 09:43:03 INFO - 'halt_on_failure': False, 09:43:03 INFO - 'name': 'check_screen_resolution'},), 09:43:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:43:03 INFO - 'cmd': ('bash', 09:43:03 INFO - '-c', 09:43:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:43:03 INFO - 'enabled': True, 09:43:03 INFO - 'halt_on_failure': False, 09:43:03 INFO - 'name': 'check_screen_resolution'},), 09:43:03 INFO - 'run_cmd_checks_enabled': True, 09:43:03 INFO - 'sps_profile': False, 09:43:03 INFO - 'sps_profile_interval': 0, 09:43:03 INFO - 'suite': 'dromaeojs', 09:43:03 INFO - 'system_bits': '32', 09:43:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:43:03 INFO - 'title': 't-yosemite-r7-0070', 09:43:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:43:03 INFO - 'use_talos_json': True, 09:43:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:43:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:43:03 INFO - 'work_dir': 'build'} 09:43:03 INFO - ##### 09:43:03 INFO - ##### Running clobber step. 09:43:03 INFO - ##### 09:43:03 INFO - Running pre-action listener: _resource_record_pre_action 09:43:03 INFO - Running main action method: clobber 09:43:03 INFO - rmtree: /builds/slave/test/build 09:43:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:43:05 INFO - Running post-action listener: _resource_record_post_action 09:43:05 INFO - ##### 09:43:05 INFO - ##### Running read-buildbot-config step. 09:43:05 INFO - ##### 09:43:05 INFO - Running pre-action listener: _resource_record_pre_action 09:43:05 INFO - Running main action method: read_buildbot_config 09:43:05 INFO - Using buildbot properties: 09:43:05 INFO - { 09:43:05 INFO - "project": "", 09:43:05 INFO - "product": "firefox", 09:43:05 INFO - "script_repo_revision": "production", 09:43:05 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs", 09:43:05 INFO - "repository": "", 09:43:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs", 09:43:05 INFO - "buildid": "20160708080626", 09:43:05 INFO - "pgo_build": "False", 09:43:05 INFO - "basedir": "/builds/slave/test", 09:43:05 INFO - "buildnumber": 25, 09:43:05 INFO - "slavename": "t-yosemite-r7-0070", 09:43:05 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:43:05 INFO - "platform": "yosemite_r7", 09:43:05 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 09:43:05 INFO - "branch": "mozilla-beta", 09:43:05 INFO - "repo_path": "releases/mozilla-beta", 09:43:05 INFO - "revision": "b6fbb6d3fb58243dcf9a72e741a028bb7fed3540", 09:43:05 INFO - "stage_platform": "macosx64", 09:43:05 INFO - "builduid": "aa0b963c60e8450ea4f7f24a6e034714", 09:43:05 INFO - "slavebuilddir": "test" 09:43:05 INFO - } 09:43:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg. 09:43:05 INFO - Running post-action listener: _resource_record_post_action 09:43:05 INFO - ##### 09:43:05 INFO - ##### Running download-and-extract step. 09:43:05 INFO - ##### 09:43:05 INFO - Running pre-action listener: _resource_record_pre_action 09:43:05 INFO - Running main action method: download_and_extract 09:43:05 INFO - mkdir: /builds/slave/test/build/tests 09:43:05 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')]} 09:43:05 INFO - https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json matches https://queue.taskcluster.net 09:43:05 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:43:05 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.test_packages.json 09:43:05 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:07 INFO - Downloaded 1165 bytes. 09:43:07 INFO - Reading from file /builds/slave/test/build/firefox-48.0.en-US.mac.test_packages.json 09:43:07 INFO - Using the following test package requirements: 09:43:07 INFO - {u'common': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:43:07 INFO - u'cppunittest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.cppunittest.tests.zip'], 09:43:07 INFO - u'gtest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.gtest.tests.zip'], 09:43:07 INFO - u'jittest': [u'firefox-48.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 09:43:07 INFO - u'mochitest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.mochitest.tests.zip'], 09:43:07 INFO - u'mozbase': [u'firefox-48.0.en-US.mac.common.tests.zip'], 09:43:07 INFO - u'reftest': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.reftest.tests.zip'], 09:43:07 INFO - u'talos': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.talos.tests.zip'], 09:43:07 INFO - u'web-platform': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.web-platform.tests.zip'], 09:43:07 INFO - u'xpcshell': [u'firefox-48.0.en-US.mac.common.tests.zip', 09:43:07 INFO - u'firefox-48.0.en-US.mac.xpcshell.tests.zip']} 09:43:07 INFO - Downloading packages: [u'firefox-48.0.en-US.mac.common.tests.zip'] for test suite category: common 09:43:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:07 INFO - https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:43:07 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:43:07 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:43:07 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:09 INFO - Downloaded 18351528 bytes. 09:43:09 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'] 09:43:09 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 09:43:12 INFO - Return code: 0 09:43:12 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 09:43:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:12 INFO - https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 09:43:12 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:43:12 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.common.tests.zip 09:43:12 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:20 INFO - Downloaded 18351528 bytes. 09:43:20 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'] 09:43:20 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 09:43:24 INFO - Return code: 0 09:43:24 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')]} 09:43:24 INFO - https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 09:43:24 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:43:24 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.talos.tests.zip 09:43:24 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:25 INFO - Downloaded 10840145 bytes. 09:43:25 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'] 09:43:25 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 09:43:25 INFO - Return code: 0 09:43:25 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')]} 09:43:25 INFO - https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg matches https://queue.taskcluster.net 09:43:25 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:43:25 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:43:25 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0.en-US.mac.dmg 09:43:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/fHydg6otRG2ZPAWvgsE2HA/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 09:43:29 INFO - Downloaded 86183088 bytes. 09:43:29 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:43:29 INFO - mkdir: /builds/slave/test/properties 09:43:29 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:43:29 INFO - Writing to file /builds/slave/test/properties/build_url 09:43:29 INFO - Contents: 09:43:29 INFO - build_url:https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg 09:43:30 INFO - Running post-action listener: _resource_record_post_action 09:43:30 INFO - Running post-action listener: set_extra_try_arguments 09:43:30 INFO - ##### 09:43:30 INFO - ##### Running populate-webroot step. 09:43:30 INFO - ##### 09:43:30 INFO - Running pre-action listener: _resource_record_pre_action 09:43:30 INFO - Running main action method: populate_webroot 09:43:30 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:43:30 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:43:30 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:43:30 INFO - '--tppagecycles', 09:43:30 INFO - '7'], 09:43:30 INFO - 'tests': ['tsvgm']}}, 09:43:30 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:43:30 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:43:30 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:43:30 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:43:30 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:30 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:43:30 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['damp', 'tps']}, 09:43:30 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'tests': ['damp', 'tps']}, 09:43:30 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['dromaeo_dom']}, 09:43:30 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:43:30 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['a11yr', 09:43:30 INFO - 'ts_paint', 09:43:30 INFO - 'tpaint', 09:43:30 INFO - 'sessionrestore', 09:43:30 INFO - 'sessionrestore_no_auto_restore', 09:43:30 INFO - 'tabpaint']}, 09:43:30 INFO - 'other-e10s': {'tests': ['a11yr', 09:43:30 INFO - 'ts_paint', 09:43:30 INFO - 'tpaint', 09:43:30 INFO - 'sessionrestore', 09:43:30 INFO - 'sessionrestore_no_auto_restore', 09:43:30 INFO - 'tabpaint']}, 09:43:30 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:43:30 INFO - 'ts_paint', 09:43:30 INFO - 'tpaint', 09:43:30 INFO - 'sessionrestore', 09:43:30 INFO - 'sessionrestore_no_auto_restore', 09:43:30 INFO - 'tabpaint']}, 09:43:30 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:43:30 INFO - 'ts_paint', 09:43:30 INFO - 'tpaint', 09:43:30 INFO - 'sessionrestore', 09:43:30 INFO - 'sessionrestore_no_auto_restore', 09:43:30 INFO - 'tabpaint']}, 09:43:30 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['a11yr', 09:43:30 INFO - 'ts_paint', 09:43:30 INFO - 'tpaint', 09:43:30 INFO - 'sessionrestore', 09:43:30 INFO - 'sessionrestore_no_auto_restore', 09:43:30 INFO - 'tabpaint']}, 09:43:30 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['a11yr', 09:43:30 INFO - 'ts_paint', 09:43:30 INFO - 'tpaint', 09:43:30 INFO - 'sessionrestore', 09:43:30 INFO - 'sessionrestore_no_auto_restore', 09:43:30 INFO - 'tabpaint']}, 09:43:30 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['tsvgx', 09:43:30 INFO - 'tsvgr_opacity', 09:43:30 INFO - 'tart', 09:43:30 INFO - 'tscrollx', 09:43:30 INFO - 'cart']}, 09:43:30 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:43:30 INFO - 'tsvgr_opacity', 09:43:30 INFO - 'tart', 09:43:30 INFO - 'tscrollx', 09:43:30 INFO - 'cart']}, 09:43:30 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'talos_options': ['--disable-e10s'], 09:43:30 INFO - 'tests': ['tp5o']}, 09:43:30 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'tests': ['tp5o']}, 09:43:30 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'talos_options': ['--disable-e10s', 09:43:30 INFO - '--xperf_path', 09:43:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:30 INFO - 'tests': ['tp5n']}, 09:43:30 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:43:30 INFO - 'talos_options': ['--xperf_path', 09:43:30 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:43:30 INFO - 'tests': ['tp5n']}}, 09:43:30 INFO - 'talos.zip': {'path': '', 09:43:30 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:43:30 INFO - Running post-action listener: _resource_record_post_action 09:43:30 INFO - ##### 09:43:30 INFO - ##### Running create-virtualenv step. 09:43:30 INFO - ##### 09:43:30 INFO - Running pre-action listener: _resource_record_pre_action 09:43:30 INFO - Running main action method: create_virtualenv 09:43:30 INFO - Creating virtualenv /builds/slave/test/build/venv 09:43:30 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 09:43:30 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:43:30 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:43:30 INFO - Using real prefix '/tools/python27' 09:43:30 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:43:31 INFO - Installing distribute.............................................................................................................................................................................................done. 09:43:34 INFO - Installing pip.................done. 09:43:34 INFO - Return code: 0 09:43:34 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:43:34 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')]} 09:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:34 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')]} 09:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:34 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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:34 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 09:43:34 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 09:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:34 INFO - 'HOME': '/Users/cltbld', 09:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:34 INFO - 'LOGNAME': 'cltbld', 09:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:34 INFO - 'MOZ_NO_REMOTE': '1', 09:43:34 INFO - 'NO_EM_RESTART': '1', 09:43:34 INFO - 'PAGER': '/bin/cat', 09:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:34 INFO - 'PWD': '/builds/slave/test', 09:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:34 INFO - 'SHELL': '/bin/bash', 09:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:34 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:34 INFO - 'USER': 'cltbld', 09:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:34 INFO - 'XPC_FLAGS': '0x0', 09:43:34 INFO - 'XPC_SERVICE_NAME': '0', 09:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 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 09:43:34 INFO - Cleaning up... 09:43:34 INFO - Return code: 0 09:43:34 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 09:43:34 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')]} 09:43:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:34 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')]} 09:43:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:34 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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:34 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 09:43:34 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 09:43:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:34 INFO - 'HOME': '/Users/cltbld', 09:43:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:34 INFO - 'LOGNAME': 'cltbld', 09:43:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:34 INFO - 'MOZ_NO_REMOTE': '1', 09:43:34 INFO - 'NO_EM_RESTART': '1', 09:43:34 INFO - 'PAGER': '/bin/cat', 09:43:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:34 INFO - 'PWD': '/builds/slave/test', 09:43:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:34 INFO - 'SHELL': '/bin/bash', 09:43:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:34 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:34 INFO - 'USER': 'cltbld', 09:43:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:34 INFO - 'XPC_FLAGS': '0x0', 09:43:34 INFO - 'XPC_SERVICE_NAME': '0', 09:43:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:34 INFO - Downloading/unpacking psutil>=0.7.1 09:43:34 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 09:43:34 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 09:43:34 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 09:43:34 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 09:43:34 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 09:43:34 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 09:43:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:43:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:37 INFO - Installing collected packages: psutil 09:43:37 INFO - Running setup.py install for psutil 09:43:37 INFO - building 'psutil._psutil_osx' extension 09:43:37 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 09:43:38 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 09:43:38 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 09:43:38 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 09:43:38 INFO - building 'psutil._psutil_posix' extension 09:43:38 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 09:43:38 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:43:38 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:43:38 INFO - ^ 09:43:38 INFO - 1 warning generated. 09:43:38 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 09:43:38 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:43:38 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:43:38 INFO - Successfully installed psutil 09:43:38 INFO - Cleaning up... 09:43:38 INFO - Return code: 0 09:43:38 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 09:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:38 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 09:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 09:43:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:38 INFO - 'HOME': '/Users/cltbld', 09:43:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:38 INFO - 'LOGNAME': 'cltbld', 09:43:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:38 INFO - 'MOZ_NO_REMOTE': '1', 09:43:38 INFO - 'NO_EM_RESTART': '1', 09:43:38 INFO - 'PAGER': '/bin/cat', 09:43:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:38 INFO - 'PWD': '/builds/slave/test', 09:43:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:38 INFO - 'SHELL': '/bin/bash', 09:43:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:38 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:38 INFO - 'USER': 'cltbld', 09:43:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:38 INFO - 'XPC_FLAGS': '0x0', 09:43:38 INFO - 'XPC_SERVICE_NAME': '0', 09:43:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:39 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 09:43:39 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 09:43:39 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 09:43:39 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 09:43:39 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 09:43:39 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 09:43:39 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 09:43:41 INFO - Downloading mozsystemmonitor-0.0.tar.gz 09:43:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:43:41 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 09:43:41 INFO - Installing collected packages: mozsystemmonitor 09:43:41 INFO - Running setup.py install for mozsystemmonitor 09:43:41 INFO - Successfully installed mozsystemmonitor 09:43:41 INFO - Cleaning up... 09:43:41 INFO - Return code: 0 09:43:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:43: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')]} 09:43:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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')]} 09:43:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43: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', '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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:43: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 blobuploader==1.2.4 09:43:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:41 INFO - 'HOME': '/Users/cltbld', 09:43:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:41 INFO - 'LOGNAME': 'cltbld', 09:43:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:41 INFO - 'MOZ_NO_REMOTE': '1', 09:43:41 INFO - 'NO_EM_RESTART': '1', 09:43:41 INFO - 'PAGER': '/bin/cat', 09:43:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:41 INFO - 'PWD': '/builds/slave/test', 09:43:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:41 INFO - 'SHELL': '/bin/bash', 09:43:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:41 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:41 INFO - 'USER': 'cltbld', 09:43:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:41 INFO - 'XPC_FLAGS': '0x0', 09:43:41 INFO - 'XPC_SERVICE_NAME': '0', 09:43:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:42 INFO - Downloading/unpacking blobuploader==1.2.4 09:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:44 INFO - Downloading blobuploader-1.2.4.tar.gz 09:43:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:43:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:43:45 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:43:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:43:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:43:45 INFO - Downloading docopt-0.6.1.tar.gz 09:43:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:43:45 INFO - Installing collected packages: blobuploader, requests, docopt 09:43:45 INFO - Running setup.py install for blobuploader 09:43:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:43:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:43:45 INFO - Running setup.py install for requests 09:43:46 INFO - Running setup.py install for docopt 09:43:46 INFO - Successfully installed blobuploader requests docopt 09:43:46 INFO - Cleaning up... 09:43:46 INFO - Return code: 0 09:43:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:46 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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:46 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 09:43:46 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 09:43:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:46 INFO - 'HOME': '/Users/cltbld', 09:43:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:46 INFO - 'LOGNAME': 'cltbld', 09:43:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:46 INFO - 'MOZ_NO_REMOTE': '1', 09:43:46 INFO - 'NO_EM_RESTART': '1', 09:43:46 INFO - 'PAGER': '/bin/cat', 09:43:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:46 INFO - 'PWD': '/builds/slave/test', 09:43:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:46 INFO - 'SHELL': '/bin/bash', 09:43:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:46 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:46 INFO - 'USER': 'cltbld', 09:43:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:46 INFO - 'XPC_FLAGS': '0x0', 09:43:46 INFO - 'XPC_SERVICE_NAME': '0', 09:43:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:46 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-zw8mOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-D2hFK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-fErTQa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-anP573-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-yJsnZS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-O4mZWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-6VhpBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-DTuaf2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-7HZbhv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-jK2d3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:47 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-f_Du_8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-p4aPYH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-lJ1BPd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Rbmwzq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-GjKJ1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-J4zBUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:48 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-A8mPog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:43:48 INFO - Running setup.py install for manifestparser 09:43:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:43:48 INFO - Running setup.py install for mozcrash 09:43:48 INFO - Running setup.py install for mozdebug 09:43:49 INFO - Running setup.py install for mozdevice 09:43:49 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Running setup.py install for mozfile 09:43:49 INFO - Running setup.py install for mozhttpd 09:43:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Running setup.py install for mozinfo 09:43:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Running setup.py install for mozInstall 09:43:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:43:49 INFO - Running setup.py install for mozleak 09:43:50 INFO - Running setup.py install for mozlog 09:43:50 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:43:50 INFO - Running setup.py install for moznetwork 09:43:50 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:43:50 INFO - Running setup.py install for mozprocess 09:43:50 INFO - Running setup.py install for mozprofile 09:43:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:43:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:43:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:43:50 INFO - Running setup.py install for mozrunner 09:43:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:43:50 INFO - Running setup.py install for mozscreenshot 09:43:50 INFO - Running setup.py install for moztest 09:43:51 INFO - Running setup.py install for mozversion 09:43:51 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:43:51 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:43:51 INFO - Cleaning up... 09:43:51 INFO - Return code: 0 09:43:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:51 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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:51 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 09:43:51 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 09:43:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:51 INFO - 'HOME': '/Users/cltbld', 09:43:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:51 INFO - 'LOGNAME': 'cltbld', 09:43:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:51 INFO - 'MOZ_NO_REMOTE': '1', 09:43:51 INFO - 'NO_EM_RESTART': '1', 09:43:51 INFO - 'PAGER': '/bin/cat', 09:43:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:51 INFO - 'PWD': '/builds/slave/test', 09:43:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:51 INFO - 'SHELL': '/bin/bash', 09:43:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:51 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:51 INFO - 'USER': 'cltbld', 09:43:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:51 INFO - 'XPC_FLAGS': '0x0', 09:43:51 INFO - 'XPC_SERVICE_NAME': '0', 09:43:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:43:51 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-vmHcbF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:43:51 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)) 09:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:43:51 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-ij5ICv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:43:51 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)) 09:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:43:51 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-dXpYo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:43:51 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)) 09:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:43:51 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-0qe3gv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:43:51 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)) 09:43:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:43:51 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-sKyTJE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-QqUSDq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-3NJrO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-X8TwYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-HnjgPO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-TWGEX_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-IrQ81l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-cC3t6a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-WZO1IT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:43:52 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)) 09:43:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:43:52 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-yqAfJJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:43:53 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)) 09:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:53 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-jKAMNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:43:53 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)) 09:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:43:53 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-Etge96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:43:53 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)) 09:43:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:43:53 INFO - Running setup.py (path:/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/pip-scVF0M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:43:53 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)) 09:43:53 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)) 09:43:53 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)) 09:43:53 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)) 09:43:53 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)) 09:43:53 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)) 09:43:53 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:43:53 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 09:43:53 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 09:43:53 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 09:43:53 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 09:43:53 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 09:43:53 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 09:43:55 INFO - Downloading blessings-1.6.tar.gz 09:43:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:43:55 INFO - Installing collected packages: blessings 09:43:55 INFO - Running setup.py install for blessings 09:43:56 INFO - Successfully installed blessings 09:43:56 INFO - Cleaning up... 09:43:56 INFO - Return code: 0 09:43:56 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:43:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:43:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:43:56 INFO - Reading from file tmpfile_stdout 09:43:56 INFO - Current package versions: 09:43:56 INFO - blessings == 1.6 09:43:56 INFO - blobuploader == 1.2.4 09:43:56 INFO - docopt == 0.6.1 09:43:56 INFO - manifestparser == 1.1 09:43:56 INFO - mozInstall == 1.12 09:43:56 INFO - mozcrash == 0.17 09:43:56 INFO - mozdebug == 0.1 09:43:56 INFO - mozdevice == 0.48 09:43:56 INFO - mozfile == 1.2 09:43:56 INFO - mozhttpd == 0.7 09:43:56 INFO - mozinfo == 0.9 09:43:56 INFO - mozleak == 0.1 09:43:56 INFO - mozlog == 3.1 09:43:56 INFO - moznetwork == 0.27 09:43:56 INFO - mozprocess == 0.22 09:43:56 INFO - mozprofile == 0.28 09:43:56 INFO - mozrunner == 6.11 09:43:56 INFO - mozscreenshot == 0.1 09:43:56 INFO - mozsystemmonitor == 0.0 09:43:56 INFO - moztest == 0.7 09:43:56 INFO - mozversion == 1.4 09:43:56 INFO - psutil == 3.1.1 09:43:56 INFO - requests == 1.2.3 09:43:56 INFO - wsgiref == 0.1.2 09:43:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:43:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:56 INFO - 'HOME': '/Users/cltbld', 09:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:56 INFO - 'LOGNAME': 'cltbld', 09:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:56 INFO - 'MOZ_NO_REMOTE': '1', 09:43:56 INFO - 'NO_EM_RESTART': '1', 09:43:56 INFO - 'PAGER': '/bin/cat', 09:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:56 INFO - 'PWD': '/builds/slave/test', 09:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:56 INFO - 'SHELL': '/bin/bash', 09:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:56 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:56 INFO - 'USER': 'cltbld', 09:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:56 INFO - 'XPC_FLAGS': '0x0', 09:43:56 INFO - 'XPC_SERVICE_NAME': '0', 09:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 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)) 09:43:56 INFO - Cleaning up... 09:43:56 INFO - Return code: 0 09:43:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:43:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:43:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:43:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:43:56 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 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:43:56 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 09:43:56 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 09:43:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:43:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:56 INFO - 'HOME': '/Users/cltbld', 09:43:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:56 INFO - 'LOGNAME': 'cltbld', 09:43:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:56 INFO - 'MOZ_NO_REMOTE': '1', 09:43:56 INFO - 'NO_EM_RESTART': '1', 09:43:56 INFO - 'PAGER': '/bin/cat', 09:43:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:56 INFO - 'PWD': '/builds/slave/test', 09:43:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:56 INFO - 'SHELL': '/bin/bash', 09:43:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:43:56 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:43:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:56 INFO - 'USER': 'cltbld', 09:43:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:56 INFO - 'XPC_FLAGS': '0x0', 09:43:56 INFO - 'XPC_SERVICE_NAME': '0', 09:43:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:43:56 INFO - Downloading/unpacking jsonschema 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:43:59 INFO - Downloading/unpacking functools32 (from jsonschema) 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43: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 09:43:59 INFO - Downloading functools32-3.2.3-2.tar.gz 09:43:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:43:59 INFO - warning: no files found matching '*.txt' 09:43:59 INFO - no previously-included directories found matching 'build' 09:43:59 INFO - no previously-included directories found matching 'dist' 09:43:59 INFO - no previously-included directories found matching '.git*' 09:43:59 INFO - Installing collected packages: jsonschema, functools32 09:43:59 INFO - Running setup.py install for functools32 09:44:00 INFO - warning: no files found matching '*.txt' 09:44:00 INFO - no previously-included directories found matching 'build' 09:44:00 INFO - no previously-included directories found matching 'dist' 09:44:00 INFO - no previously-included directories found matching '.git*' 09:44:00 INFO - Successfully installed jsonschema functools32 09:44:00 INFO - Cleaning up... 09:44:00 INFO - Return code: 0 09:44:00 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 09:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:44:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:44:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:44:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cd09a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd19420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc42bdb1bb0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, '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.u1QO8zoalZ/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.k25EpZRgze/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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 09:44:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 09:44:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 09:44:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:44:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:00 INFO - 'HOME': '/Users/cltbld', 09:44:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:00 INFO - 'LOGNAME': 'cltbld', 09:44:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:00 INFO - 'MOZ_NO_REMOTE': '1', 09:44:00 INFO - 'NO_EM_RESTART': '1', 09:44:00 INFO - 'PAGER': '/bin/cat', 09:44:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:00 INFO - 'PWD': '/builds/slave/test', 09:44:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:00 INFO - 'SHELL': '/bin/bash', 09:44:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:44:00 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:44:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:00 INFO - 'USER': 'cltbld', 09:44:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:00 INFO - 'XPC_FLAGS': '0x0', 09:44:00 INFO - 'XPC_SERVICE_NAME': '0', 09:44:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:44:00 INFO - Downloading/unpacking flake8 09:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:44:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:44:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:44:02 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 09:44:02 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:03 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 09:44:03 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:03 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44: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 09:44:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 09:44:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:44:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:44:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:44:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:44:04 INFO - no previously-included directories found matching 'docs/_build' 09:44:04 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 09:44:04 INFO - Running setup.py install for pep8 09:44:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 09:44:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 09:44:04 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 09:44:04 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 09:44:04 INFO - no previously-included directories found matching 'docs/_build' 09:44:04 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 09:44:04 INFO - Successfully installed flake8 mccabe pyflakes pep8 09:44:04 INFO - Cleaning up... 09:44:04 INFO - Return code: 0 09:44:04 INFO - Running post-action listener: _resource_record_post_action 09:44:04 INFO - Running post-action listener: _start_resource_monitoring 09:44:04 INFO - Starting resource monitoring. 09:44:04 INFO - ##### 09:44:04 INFO - ##### Running install step. 09:44:04 INFO - ##### 09:44:04 INFO - Running pre-action listener: _resource_record_pre_action 09:44:04 INFO - Running main action method: install 09:44:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:44:04 INFO - Reading from file tmpfile_stdout 09:44:04 INFO - Detecting whether we're running mozinstall >=1.0... 09:44:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:44:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:44:04 INFO - Reading from file tmpfile_stdout 09:44:04 INFO - Output received: 09:44:04 INFO - Usage: mozinstall [options] installer 09:44:04 INFO - Options: 09:44:04 INFO - -h, --help show this help message and exit 09:44:04 INFO - -d DEST, --destination=DEST 09:44:04 INFO - Directory to install application into. [default: 09:44:04 INFO - "/builds/slave/test"] 09:44:04 INFO - --app=APP Application being installed. [default: firefox] 09:44:04 INFO - mkdir: /builds/slave/test/build/application 09:44:04 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'] 09:44:04 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 09:44:26 INFO - Reading from file tmpfile_stdout 09:44:26 INFO - Output received: 09:44:26 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 09:44:26 INFO - Running post-action listener: _resource_record_post_action 09:44:26 INFO - ##### 09:44:26 INFO - ##### Running run-tests step. 09:44:26 INFO - ##### 09:44:26 INFO - Running pre-action listener: _resource_record_pre_action 09:44:26 INFO - Running main action method: run_tests 09:44:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:44:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:44:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:44:26 INFO - 2016-07-08 09:44:26.501 screenresolution[1643:8049] starting screenresolution argv=screenresolution get 09:44:26 INFO - 2016-07-08 09:44:26.504 screenresolution[1643:8049] Display 0: 1600x1200x32@60 09:44:26 INFO - 2016-07-08 09:44:26.509 screenresolution[1644:8051] starting screenresolution argv=screenresolution list 09:44:26 INFO - Available Modes on Display 0 09:44:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:44:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:44:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:44:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:44:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:44:26 INFO - Intel Iris: 09:44:26 INFO - Chipset Model: Intel Iris 09:44:26 INFO - Type: GPU 09:44:26 INFO - Bus: Built-In 09:44:26 INFO - VRAM (Dynamic, Max): 1536 MB 09:44:26 INFO - Vendor: Intel (0x8086) 09:44:26 INFO - Device ID: 0x0a2e 09:44:26 INFO - Revision ID: 0x0009 09:44:26 INFO - Displays: 09:44:26 INFO - Display: 09:44:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:44:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:44:26 INFO - Display Serial Number: 1600x1200 60 09:44:26 INFO - Main Display: Yes 09:44:26 INFO - Mirror: Off 09:44:26 INFO - Online: Yes 09:44:26 INFO - Rotation: Supported 09:44:26 INFO - Return code: 0 09:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:44:26 INFO - Python 2.7.3 09:44:26 INFO - Return code: 0 09:44:26 INFO - grabbing minidump binary from tooltool 09:44:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 09:44:26 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 0x10cba8e00>, '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 0x10ca11db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cba3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:44:26 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 09:44:26 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 09:44:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:44:26 INFO - Return code: 0 09:44:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:44:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:44:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:44:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:44:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:44:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 09:44:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 09:44:27 INFO - Return code: 0 09:44:27 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/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 09:44:27 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/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0070 --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 09:44:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u1QO8zoalZ/Render', 09:44:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:44:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:44:27 INFO - 'HOME': '/Users/cltbld', 09:44:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:44:27 INFO - 'LOGNAME': 'cltbld', 09:44:27 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:44:27 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:44:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:44:27 INFO - 'MOZ_NO_REMOTE': '1', 09:44:27 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:44:27 INFO - 'NO_EM_RESTART': '1', 09:44:27 INFO - 'PAGER': '/bin/cat', 09:44:27 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:44:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:44:27 INFO - 'PWD': '/builds/slave/test', 09:44:27 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:44:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:44:27 INFO - 'SHELL': '/bin/bash', 09:44:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.k25EpZRgze/Listeners', 09:44:27 INFO - 'TMPDIR': '/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/', 09:44:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:44:27 INFO - 'USER': 'cltbld', 09:44:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:44:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:44:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:44:27 INFO - 'XPC_FLAGS': '0x0', 09:44:27 INFO - 'XPC_SERVICE_NAME': '0', 09:44:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:44:27 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/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0070', '--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 09:44:28 INFO - mozversion application_buildid: 20160708080626 09:44:28 INFO - mozversion application_changeset: b6fbb6d3fb58243dcf9a72e741a028bb7fed3540 09:44:28 INFO - mozversion application_display_name: Firefox 09:44:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:44:28 INFO - mozversion application_name: Firefox 09:44:28 INFO - mozversion application_remotingname: firefox 09:44:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 09:44:28 INFO - mozversion application_vendor: Mozilla 09:44:28 INFO - mozversion application_version: 48.0 09:44:28 INFO - mozversion platform_buildid: 20160708080626 09:44:28 INFO - mozversion platform_changeset: b6fbb6d3fb58243dcf9a72e741a028bb7fed3540 09:44:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 09:44:28 INFO - mozversion platform_version: 48.0 09:44:28 INFO - using testdate: 1467996268 09:44:28 INFO - actual date: 1467996268 09:44:28 INFO - starting webserver on 'localhost:49272' 09:44:28 INFO - SUITE-START | Running 2 tests 09:44:28 INFO - TEST-START | dromaeo_css 09:44:28 INFO - operating with platform_type : mac_ 09:44:28 INFO - Initialising browser for dromaeo_css test... 09:44:28 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpYi3cKX/profile http://localhost:49272/getInfo.html) 09:44:30 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 09:44:30 INFO - PROCESS | 1662 | colorDepth:24 09:44:30 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 09:44:30 INFO - PROCESS | 1662 | __metrics 09:44:30 INFO - PROCESS | 1662 | 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" 09:44:30 ERROR - PROCESS | 1662 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 09:44:31 INFO - TEST-INFO | 1662: exit 0 09:44:31 INFO - Browser initialized. 09:44:31 INFO - Running cycle 1/1 for dromaeo_css test... 09:44:31 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmpYi3cKX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:44:43 INFO - PROCESS | 1666 | RSS: Main: 161656832 09:44:43 INFO - PROCESS | 1666 | 09:45:34 INFO - PROCESS | 1666 | 1467996334400 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] 09:47:44 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 09:47:45 INFO - PROCESS | 1666 | RSS: Main: 325627904 09:47:45 INFO - PROCESS | 1666 | 09:50:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 09:50:49 INFO - PROCESS | 1666 | RSS: Main: 304939008 09:50:49 INFO - PROCESS | 1666 | 09:53:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 09:53:52 INFO - PROCESS | 1666 | RSS: Main: 305618944 09:53:52 INFO - PROCESS | 1666 | 09:56:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 09:56:54 INFO - PROCESS | 1666 | RSS: Main: 302551040 09:56:54 INFO - PROCESS | 1666 | 09:59:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 09:59:58 INFO - PROCESS | 1666 | RSS: Main: 274591744 09:59:58 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 10:03:01 INFO - PROCESS | 1666 | RSS: Main: 326074368 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | __start_tp_report 10:03:01 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 10:03:01 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 10:03:01 INFO - PROCESS | 1666 | |i|pagename|runs| 10:03:01 INFO - PROCESS | 1666 | |0;dojo.html;3035.8482075896204;3549.325628130655;3633.091727068233;3668.3396988542054;3686.428603566448;5862.384449632791;5892.08672655738;5897.144913987469;5908.970455147723;5923.022701254288;5968.67172305523;5991.520678345732;6011.789587364443;6014.5489088318445;6016.398360163981;5275.736213189344;5340.225667278242;5379.596530260226;5402.594805389612;5431.456854314576;5378.731063446812;5448.210009548613;5449.100898351767;5449.7275136243225;5453.64551304164;5356.54469370104;5417.45825417459;5436.130219164935;5444.537214336786;5459.808906688267;5423.186522021709;5443.891122177574;5449.3188351456065;5461.5357694596005;5485.314335708036;5229.058769421503;5251.737413129366;5377.919331210035;5386.057439947994;5391.541718953894;4769.95230047702;5692.5161361284345;5782.268774151062;5811.302643682785;5819.7963071292725;6292.653904035276;6315.589486683397;6326.335734747877;6327.24073111225;6328.1773369462135;5850.561207909382;6235.968820155916;6273.905891411632;6362.968185159091;6373.23521177457;5988.131720900445;6026.6986650667295;6057.697115144225;6058.939410605925;6064.332923378469;6428.678566071678;6431.646259455775;6437.066625339299;6440.54916155874;6454.354564543508;6187.16473276111;6256.217972753406;6339.936600633934;6377.74489020434;6381.776637817747;6326;6339.556231063873;6348.841278968062;6355.046742988497;6355.428011478989;6250.6874656267;6288.842778930563;6317.241930968221;6323.30443651198;6330.968345158337;6224.813255602339;6245.219347581552;6319.842003949938;6331.176946996953;6369.777057802955;5045.621578381636;5082.923756143661;5086.694798312114;5095.337606111256;5096.643234973591;4545.568171023748;4579.427571553556;4585.10590434862;4595.356650068993;4599.816007359735;6116.847078823065;6206.968965155235;6207.131001659771;6214.751409943552;6225.159603453566;6375.10748495211;6397.424231819158;6402.167718196604;6408.839779005562;6411.807645770635;6015.849603759941;6116.724747386378;6168.383161683885;6182.536309776785;6189.50483961282;5575.944240557624;5590.86022849432;5651.689157096399;5660.660360378308;5675.744591493392;5494.478024587658;6539.149910511603;6557.9344206557325;6579.046038324512;6582.341765823475;6275.529335299962;6327.462165715966;6380.03604935067;6404.871902562016;6410.326915673787;6567.343265673395;6623.304553021863;6624.337566243337;6627.5360724750735;6637.734490620321;6306.684665766602;6307.905381419374;6318.4313411793155;6324;6332.2717887441695;6134.130457564626;6241.812745617639;6277.529185311211;6350.301466838551;6366.904496432465;6311.0217916223055;6312.715927783169;6333.334999824952;6343.492520598434;6349.238091429058;6326.335734747785;6363.649999250086;6369.14016607752;6373.936260637334;6381.553291269566;6130.5402094842175;6162.106494558354;6200.534959878118;6211.782587609412;6280.497560195265;6145.84635384119;6280.5289603279025;6287.497000239879;6297.811065668037;6300.464460520907;6145.617236121837;6185.165002724754;6221.222347206599;6266.624002559861;6280.748770049329 10:03:01 INFO - PROCESS | 1666 | |1;ext.html;529.4017759931277;534.5509771791694;567.4297331182164;576.0236399303182;615.375393975115;26536.867315663418;32805.195714235706;32818.50772238418;32871;32973.34053318932;35887.64112358876;41080.58919410813;41998.79000604993;42000;42186.15627687444;9659.951700241489;9779.511024448784;9811.705648830512;9812.46031468269;9825.410475371466;9192.034836342187;9556.232097062066;9671.516424178799;9679.983601721824;9688.46713430761;11976.221545599552;12205.145639805218;12226.633201003984;12258.387080645976;12262.509499620004;11729.765404691901;11901.940490297493;11912.404379780975;11917.22538035025;11920.582779602759;11633.59282425111;11739.941300293443;11749.412529373583;11761.647150585497;11902.58340958071;39750.60872869465;39875.80372588827;39914.201715965515;40010.399844002364;40026.79986600077;3311.8840840570465;3329.517220003086;3477.1480987158307;3543.167355671415;3655.744097913147;28862.422751545062;29507.114786556438;30783.384332313453;30857.919972800843;31325.37349253025;2761.226856480189;2776.7778577713734;2793.2877116335408;2859.7283258090447;2861.556458748897;385471;386026;386886;387209;388438;36965;37905.62094379076;37969.430458543146;68677.65661171662;70112.64943675249;37063.81468092696;37179.62820371816;37202.62797372046;37215.25569488595;37326.813365933;16050.672652465328;16166.919165404333;16222.35110595563;16235.188240587924;16242.75635865463;13593.52422665202;13693.452261909611;13704.45182192721;13704.588862334145;13707.794383084261;8043.356531477468;8196.13940536247;8527.147285271547;8558.315334773204;8707.47755134694;3496.1434448559758;3553.3781588222473;3556.76881002734;3565.037439891267;3584.1577229351083;883.8320719063357;924.9861252081224;1043.7527155049643;1069.652362982034;1124.145649306533;30711;30803.84598076995;30972.690273097433;31392.529112063337;31427;1158.2760774515928;1161.936093514848;1178.3401295274673;1224.5040758492826;1240.460399726122;15662.373505059897;15670.98138620986;15688.294026768823;16003.759943600855;16023;651958;652489;655647;661019;663006.6849665818;64164;64845;64848.027279590846;64940.350596494376;65184.674076629315;1405.6907480354305;1444.5955132563108;1564.929578168985;1565.3190861975077;1630.6086539230737;29258.7074129256;29730.108096757132;30743.385132297677;31017.0694879154;31297.687023129478;53331.73334133304;53662;53678;53696.1945570809;53877.46122538725;389539;405678;406702;406921;407472;25276.241712748648;28183;28305;28619.856900715364;28805;43885;44056.559434405244;44777.55222447734;45768.31347529723;45851.3122303159;35010;35748.28503429865;36139;36192.91421257366;36529;37548.06129846776;37674.62325376711;38141.61858381492;38291;38384.040398989135;38852.6114738849;39544.40683389809;41093.79453102715;41255;41396.37905431481;32303.900246483903;33858.3228335427;33900.81347152838;33919.491207631414;33956.66043339535;24872;25301.240962771146;25402.364940875887;25533.872330638227;25749.613755793303 10:03:01 INFO - PROCESS | 1666 | |2;jquery.html;419.2913975381606;421.46473978047896;423.0924666590164;427.64291816333343;434.88692939835653;20526.178952841867;20754.169833206655;21031.894840525776;21446.356609301725;21510.784892151074;20589.897050514763;20658.38024859253;21822.90885455725;21832.8908355458;21855.016524256407;5720.084786434171;6162.22972128484;6163.414475624809;6163.599366041193;6167.044108163242;6006.639601623895;6019.277686677605;6114.1745864308195;6123.357047510014;6131.662758548278;5983.970080149593;6262.12330273762;6264.56148069633;6267.717952692117;6268;5920.230370051909;6131.7240724167295;6194.318624951252;6195.473384762301;6204.565680402374;6083.30042045166;6087.4825639820665;6100.084987251903;6103.267607887082;6104.572679912408;11455.484503197376;13766.31168441574;13835.51575694856;14345.569632911029;14353.425862965469;2281.726192856868;2382.60686986647;2539.771420572139;2615.882285297166;2859.985700071507;10231.386116833013;10764.78470430587;14565;15118.941674082818;15934.442294519753;1736.8089510153873;1782.6523827853573;1821.2068982694623;1827.0316732131992;1895.6966885298425;65136;67139.99290010653;108032;127245.36377318054;127350.08974865385;11885.88114118865;12013.099017573717;12134.635960921187;12136.757264854654;12167;14016.088954217943;14357.21035343066;14374.281285935871;14401.351939162763;14410.42358305656;7575.886361704579;9138.406003609743;9185.265178785814;9272;9359.064093590723;9183.357164998386;9184.678536251198;9206.757087793196;9292.303077269096;9300.953495232481;3982;4765.7140571565815;4819.7108173509705;4830.637702172351;4839.322494850724;1963.106786412179;2006.6588679924448;2007.5483016321502;2021.6158929803291;2029.939101826918;923.1368670293298;946.2997381937455;1004.3672486333563;1088.7713580148259;1113.276370359273;20121;20180.19279228844;20447;20723.792762072186;20815.79184208169;986.1026465915982;1008.7881544875659;1016.501914062119;1037.8656457498312;1081.5673730507704;10549.947250263853;11082.279651822606;11205.495752691148;11648.009919156797;11933.820992684943;421220;641492;642781;646314;651729;121262;123710;129522.35238823746;129966;136016.31991839976;1007.1640538353149;1078.0405439159;1096.413418820943;1097.1332647208617;1097.9725506862233;18209.726854097466;18260.72608910894;18280.360187393377;18657.346992855728;18892.33876814305;54119.72940135274;56439.15341269802;56786;56895;57573.4242657568;70247;77433.61283193548;78752.60623697074;83751;86220;7326.6702998364135;8395.538245396561;8424.11546787603;8501.107383724868;8768.956155219183;11475.196736228718;12328.383580820744;12369.763023697558;12516.436760345625;13051.673708157372;7139.8572028560175;10812.297200681931;11051.336919784839;11263.718407039889;11317.377544235145;10051.949740251252;10849.837252441062;11205.495752690986;11226.831597526207;11383.317000979967;11536.480858361561;11550.768984620428;11570.363630000431;11772.058235341323;11905.226160299553;7039.225685174524;8506.489610623383;8543.017552981239;8609.913900861162;8678.349123815866;8540.27407670355;10786.406747628957;10986.230963832775;11916.821247681464;11918.880811192124 10:03:01 INFO - PROCESS | 1666 | |3;mootools.html;1481.9184944828035;1485.3093311610098;1526.1377321813172;1866.6266746650674;2035.053700029487;8426.747197584074;9087.681931132413;9192.63229470822;9197.172254497094;9214.861777073349;7381.48329616927;9321.766955826091;9327.766805829857;9328.253739700825;9335.95332023339;2163.8593491423085;2512.5603019471564;2571.1643715792347;2572.7298633643477;2575.4032499850027;2447.1068060158004;2534.6071358939394;2542.720300766914;2545.2237067694346;2549.069589599792;2541.097910241856;2570.3702592864774;2586.2629150692055;2589.9482010359875;2592.857392843393;2551.553478141318;2583.599542070982;2589.9741002590017;2590.961135582968;2597.857117858517;2542.7965762738936;2582.9354266143496;2590.6243594678854;2592.3648706066947;2601.9479610407684;9112.952010518742;9469.384490008197;9527.237820974306;9536.570854311572;9555.713328600154;5676.063449530822;5839.270091238595;5873.1190321451695;5890.263717035371;5901.321348044987;3533.063738109403;3625.8912232633065;3681.4293784463453;3702.87039953603;3708.35103856824;994.721477986165;1036.233187441288;1038.3562191441279;1039.7660526381503;1039.8475156426125;12559.058070644738;12645.367731613382;12807.61577152687;12848.614541563767;12867.80698289517;4813.711177329372;4902.48523904992;4942.233953737176;4986.177280748709;5016.473270306638;10057.597696092074;10058.647947321808;10060.798784024279;10070.899291006996;10083.445410502347;5716.399778023331;5763.106718458645;5776.133579963039;5776.624519406225;5786.132080188006;4746.786394612251;4862.975685121552;5134.024535338273;5305.840824775263;5338.092524270884;4051.8987025324013;4080.2451546464;4131.380292956021;4173.08192197716;4179.185058913544;1272.395612084271;1286.093304220541;1288.3171918883006;1298.8571257161705;1299.4672184404349;913.8400779863497;944.3531181140941;953.2564599612314;970.708787363788;1002.1932344462632;5982.282126144803;6098.420650038239;6219.657918814418;6228.844278893064;6309.873802524015;480.42829033450084;484.41627838454576;492.50257240186954;890.528020149322;1025.349187807629;3922.058705910561;3994.8801535953967;4028.3353246713964;4044.5753195914594;4083.2854250506043;10816.351018938889;10949.4525273736;11290.379029153475;11298.152638551977;11312.095032397388;11163.609273675382;11285.379304138187;11453.713657158638;11455.026322762491;11458.968692817687;7389.298016688406;8636.877205963185;8793.340499462438;8793.692220772371;8920.598573064228;6021.277446706424;6046.516278697605;6073.000797254692;6084.908726369196;6136.29432615255;10083.092521673085;10260.127889129508;10430.843537347098;10443.530041148015;10454.477276136315;10295.691129266133;10626.574937002433;10645.8935410648;10647.520861561403;10889.0199882011;7223.097112860893;7379.151397589346;7697.384209263134;8004.959975200087;8139.674413023413;8780.517071561126;8780.736577902673;8834.160754728291;8838.160374764388;8869.290456763572;7674.501157424749;7752.7674169775;7811.4922530035365;7885.921140788748;7915.84168316642;7553.546787192786;7598.544087354777;7629.54222746637;7741;7770.222977702185;7318.158411782713;7369.852602948018;7378.778636640909;7475.551466912003;7481.70073197066;5034.647574669738;5037.874053148701;5128.102582048201;5241.475852414728;5242.790288388422;5136.409312928911;5142;5240.528352448298;5374.543163831118;5433.483819037185 10:03:01 INFO - PROCESS | 1666 | |4;prototype.html;324.92851572654024;331.3389787374188;338.8729226540047;341.2169071979806;341.3701720325998;6318.715657795398;6476.093346931428;6478.740850365985;6479.676016199195;6524.608523488588;6058.818235452944;6360.300366959642;6536.803895883119;6543.05125756765;6590.275069742336;4886.096072226632;4900.093482705693;4912.336834527347;4921.80312787488;4923.064617722639;4666.626669866419;4722.480527142012;4836.322914791932;4893.3638626978445;4910.926336104943;4794.592459640935;4840.298156767267;4869.074875773609;4879.1461494238365;4879.731614761205;4865.9756701216265;4872.123017856784;4875.24433712775;4878.634102442331;4915.705057696549;4212.578742125794;4215.57844215576;4218.472690913636;4221.176870510221;4223.809928553192;6354.904676429857;6360.3321651226415;6362.840928976766;6374.681265936684;6375.808725738235;5741.942580574224;5758.078707406795;5758.481736643722;5768.711564421762;5768.769249230026;5611.382747897769;6987.7903662889685;6989.93010069903;7013.368796808311;7019.543729657556;3695.9445608315896;4563.292689633112;4674.182018146811;4685.8594242172785;4705.952940470585;5588.770470496484;7192.748253811092;7414.6292685365515;7425.740099096506;7440.627968601549;4627.167109920179;4632.791524381411;4635.814567417266;4643.164230438553;4647.000894807565;4652.581267685857;4822;4822.445418776815;4878.43897951733;4908.950910490921;4099.692523060782;4956.3804524434445;5401.405845357008;5630.380658127603;5698.116791897262;7025.92974070263;7044.506884518137;7058.082449281561;7078.115235595551;7081.787546373617;1558.7428074367601;1623.9350425982827;1656.6521030583713;1681.7141086015406;1683.2257161705509;1015.6952914125733;1026.2046913641987;1042.3849928542197;1044.7753732947301;1047.675220681591;5931.169636250928;6180.443760061616;6535.117759102461;6674.733010679615;6681.432078273304;7148.142222933177;7276.090488688914;7323.7436689715605;7441.069866266716;7466.328030477283;5477.931803298318;6660.100886380276;6776.525643204928;6786.796396108125;6790.083338749305;3861.864834730771;3909.7654140751647;3920.157166209279;3922.0979174789386;3923.431102490123;7758.650860711282;7766.456348055694;7778.105517865405;7793.844123117506;7794.883076753853;7300.926990730131;7500.212477689764;7542.547447153188;7571.583562903983;7604.353629941406;7686.423518236154;7730.884036739566;7734.03324584427;7744.148143704188;7799.844003119792;6015.849603759941;7401.666924988496;7404.703811847466;7410.777221758565;7418.146913104846;7246.130464344313;7248.021517095125;7251.419886408969;7260;7274.854502909806;7417.109946806418;7743.148253691977;7765.495242809199;7812.3359514439635;7830.41271904598;4433.733975961452;4588;4687.882802929817;4689.695169813951;4697.5302469754115;6555.672216389257;6985.545939513915;7114.075170227838;7199.2440793715905;7350.154732205651;5711.257536520392;7119;7141.714331426685;7152.606606636685;7153.320434558708;7131.572105673677;7138.179109402277;7146.785596432115;7148.821279468055;7154.105736782903;6673.83315417099;6961.920902260158;6969.0940177776565;6978.441724662116;6979.5114341997605;5735.598508104393;7038.929610703827;7073.964630176816;7096.858062838817;7103.2541583133025;6944.305569443218;6962.23415424313;6972.163340398982;6973.721051157897;6980.860382792417 10:03:01 INFO - PROCESS | 1666 | |5;yui.html;703.9859202815943;743.5352904434721;766.0007380736278;780.5199802121697;789.1398375770411;15292.84707152928;15632.218389080557;15633.452829150981;15650.686986260282;15658;12277.324747138904;12440.502379904816;12443.626691199255;12443.875561244386;12464.81302780459;770.0912922751152;792.0890975378303;930.7114794413753;956.2350119904083;959.0984474593894;961.3510880155902;964.9903500965006;973.9366941148838;974.6491263145298;978.5400861595039;824.8185399212164;1031.056583226347;1036.9222308326869;1038.9064984151426;1040.2770074798018;1001.6894762623574;1025.2208321675505;1026.0611540440489;1027.2347101409423;1027.342500799489;766.1074847802304;766.4251811141644;768.8077980504873;770.803445121496;771.9150893401721;15430.614234644112;15500.844991550053;15507.379704811794;15507.767383489258;15524.301406436738;12921;13202.66993325165;13246.853185291073;13318;13533.18800871941;10358.171346292278;10398.584056637725;10458.267921245515;10705;10782.73043173927;3557.324108419392;3601.441776524643;3618.7647802892993;3622.800745958971;3699.7040236780995;61586;79516;80319.5984020082;85213;85412.57293713492;7844.64699088531;9662.75843103914;9795.853062204073;9985.550650220757;10016.899831001596;22904.885475572515;22952.770472295397;22962.540749184922;22964.19625313106;23013;7650.311471967439;7785.64964576584;7877.921220787834;8047.798805029921;8294.336453083739;4308.224519586442;4317.7193482423545;4327.913441731148;4367.432233809584;4620.653450991189;3737.1591391936595;3786.3563194257044;3797.3354662933875;3801.1447424329303;3813.1801662642665;530.9044372013075;572.7995201679379;614.2628845385556;687.6458623808743;702.4134847402374;586.1178925716769;587.7472686744741;607.2106261859565;622.9221347331584;629.329763801559;6958.8956165657555;8017.318527925192;8116.188381161836;8130.471519351223;8288.875666865002;545.8771776350289;546.710243570908;566.0207840435986;598.7066337494597;637.3594537489886;3829.6744776694295;3876.2635099331037;3889.474920885644;3941.8423263069158;3984.3425834736945;82206;82651;83002.16997829944;83120.58439707763;83526.74709879357;73819.26180738123;74292.62853685806;76321;76493.85259221116;77689.44621107547;67652.64694706078;67888;68707.62584748377;68817;69134;7660.616969151632;7807;7808.921910781047;7820;7837.7648670539975;48052.7597362025;48312.55062348135;48921;49236.2614560789;49919;60136;62936.74126517352;65141.67429162824;65183;66913.66172676616;9607.66618969032;9831.754206144904;10017.198624109908;10090.596376144871;10141.138003269805;10724.571017159225;10883.510242038968;11005;11271.154663400113;11334.829977550178;9669.371490853073;11155.55377784912;11405.372704501;11520.366379849189;11594.6521604352;10970.177236707119;11237.157213208879;11501.712457188638;11522.207676493877;11585.304881707125;11002.33985960845;11065.336079835235;11186.608468703882;11407.144464165054;11410.14423918226;9399.389039712396;9548.283878708986;9573.473458959545;9635.13283804461;9725.221982241263;9511.762205944906;9666.7583310415;9697.806043878942;9775.511224438891;9783.510824458606 10:03:01 INFO - PROCESS | 1666 | __end_tp_report 10:03:01 INFO - PROCESS | 1666 | __start_cc_report 10:03:01 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,1576 10:03:01 INFO - PROCESS | 1666 | __end_cc_report 10:03:01 INFO - PROCESS | 1666 | __startTimestamp1467997381722__endTimestamp 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | ------- Summary: start ------- 10:03:01 INFO - PROCESS | 1666 | Number of tests: 6 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | [#0] dojo.html Cycles:165 Average:5926.44 Median:6207.13 stddev:644.85 (10.4%) stddev-sans-first:605.65 10:03:01 INFO - PROCESS | 1666 | Values: 3035.8 3549.3 3633.1 3668.3 3686.4 5862.4 5892.1 5897.1 5909.0 5923.0 5968.7 5991.5 6011.8 6014.5 6016.4 5275.7 5340.2 5379.6 5402.6 5431.5 5378.7 5448.2 5449.1 5449.7 5453.6 5356.5 5417.5 5436.1 5444.5 5459.8 5423.2 5443.9 5449.3 5461.5 5485.3 5229.1 5251.7 5377.9 5386.1 5391.5 4770.0 5692.5 5782.3 5811.3 5819.8 6292.7 6315.6 6326.3 6327.2 6328.2 5850.6 6236.0 6273.9 6363.0 6373.2 5988.1 6026.7 6057.7 6058.9 6064.3 6428.7 6431.6 6437.1 6440.5 6454.4 6187.2 6256.2 6339.9 6377.7 6381.8 6326.0 6339.6 6348.8 6355.0 6355.4 6250.7 6288.8 6317.2 6323.3 6331.0 6224.8 6245.2 6319.8 6331.2 6369.8 5045.6 5082.9 5086.7 5095.3 5096.6 4545.6 4579.4 4585.1 4595.4 4599.8 6116.8 6207.0 6207.1 6214.8 6225.2 6375.1 6397.4 6402.2 6408.8 6411.8 6015.8 6116.7 6168.4 6182.5 6189.5 5575.9 5590.9 5651.7 5660.7 5675.7 5494.5 6539.1 6557.9 6579.0 6582.3 6275.5 6327.5 6380.0 6404.9 6410.3 6567.3 6623.3 6624.3 6627.5 6637.7 6306.7 6307.9 6318.4 6324.0 6332.3 6134.1 6241.8 6277.5 6350.3 6366.9 6311.0 6312.7 6333.3 6343.5 6349.2 6326.3 6363.6 6369.1 6373.9 6381.6 6130.5 6162.1 6200.5 6211.8 6280.5 6145.8 6280.5 6287.5 6297.8 6300.5 6145.6 6185.2 6221.2 6266.6 6280.7 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | [#1] ext.html Cycles:180 Average:61836.71 Median:3560.90 stddev:133152.97 (3739.3%) stddev-sans-first:133446.94 10:03:01 INFO - PROCESS | 1666 | Values: 529.4 534.6 567.4 576.0 615.4 26536.9 32805.2 32818.5 32871.0 32973.3 35887.6 41080.6 41998.8 42000.0 42186.2 9660.0 9779.5 9811.7 9812.5 9825.4 9192.0 9556.2 9671.5 9680.0 9688.5 11976.2 12205.1 12226.6 12258.4 12262.5 11729.8 11901.9 11912.4 11917.2 11920.6 11633.6 11739.9 11749.4 11761.6 11902.6 39750.6 39875.8 39914.2 40010.4 40026.8 3311.9 3329.5 3477.1 3543.2 3655.7 28862.4 29507.1 30783.4 30857.9 31325.4 2761.2 2776.8 2793.3 2859.7 2861.6 385471.0 386026.0 386886.0 387209.0 388438.0 36965.0 37905.6 37969.4 68677.7 70112.6 37063.8 37179.6 37202.6 37215.3 37326.8 16050.7 16166.9 16222.4 16235.2 16242.8 13593.5 13693.5 13704.5 13704.6 13707.8 8043.4 8196.1 8527.1 8558.3 8707.5 3496.1 3553.4 3556.8 3565.0 3584.2 883.8 925.0 1043.8 1069.7 1124.1 30711.0 30803.8 30972.7 31392.5 31427.0 1158.3 1161.9 1178.3 1224.5 1240.5 15662.4 15671.0 15688.3 16003.8 16023.0 651958.0 652489.0 655647.0 661019.0 663006.7 64164.0 64845.0 64848.0 64940.4 65184.7 1405.7 1444.6 1564.9 1565.3 1630.6 29258.7 29730.1 30743.4 31017.1 31297.7 53331.7 53662.0 53678.0 53696.2 53877.5 389539.0 405678.0 406702.0 406921.0 407472.0 25276.2 28183.0 28305.0 28619.9 28805.0 43885.0 44056.6 44777.6 45768.3 45851.3 35010.0 35748.3 36139.0 36192.9 36529.0 37548.1 37674.6 38141.6 38291.0 38384.0 38852.6 39544.4 41093.8 41255.0 41396.4 32303.9 33858.3 33900.8 33919.5 33956.7 24872.0 25301.2 25402.4 25533.9 25749.6 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | [#2] jquery.html Cycles:180 Average:34589.55 Median:20784.98 stddev:101112.55 (486.5%) stddev-sans-first:101363.64 10:03:01 INFO - PROCESS | 1666 | Values: 419.3 421.5 423.1 427.6 434.9 20526.2 20754.2 21031.9 21446.4 21510.8 20589.9 20658.4 21822.9 21832.9 21855.0 5720.1 6162.2 6163.4 6163.6 6167.0 6006.6 6019.3 6114.2 6123.4 6131.7 5984.0 6262.1 6264.6 6267.7 6268.0 5920.2 6131.7 6194.3 6195.5 6204.6 6083.3 6087.5 6100.1 6103.3 6104.6 11455.5 13766.3 13835.5 14345.6 14353.4 2281.7 2382.6 2539.8 2615.9 2860.0 10231.4 10764.8 14565.0 15118.9 15934.4 1736.8 1782.7 1821.2 1827.0 1895.7 65136.0 67140.0 108032.0 127245.4 127350.1 11885.9 12013.1 12134.6 12136.8 12167.0 14016.1 14357.2 14374.3 14401.4 14410.4 7575.9 9138.4 9185.3 9272.0 9359.1 9183.4 9184.7 9206.8 9292.3 9301.0 3982.0 4765.7 4819.7 4830.6 4839.3 1963.1 2006.7 2007.5 2021.6 2029.9 923.1 946.3 1004.4 1088.8 1113.3 20121.0 20180.2 20447.0 20723.8 20815.8 986.1 1008.8 1016.5 1037.9 1081.6 10549.9 11082.3 11205.5 11648.0 11933.8 421220.0 641492.0 642781.0 646314.0 651729.0 121262.0 123710.0 129522.4 129966.0 136016.3 1007.2 1078.0 1096.4 1097.1 1098.0 18209.7 18260.7 18280.4 18657.3 18892.3 54119.7 56439.2 56786.0 56895.0 57573.4 70247.0 77433.6 78752.6 83751.0 86220.0 7326.7 8395.5 8424.1 8501.1 8769.0 11475.2 12328.4 12369.8 12516.4 13051.7 7139.9 10812.3 11051.3 11263.7 11317.4 10051.9 10849.8 11205.5 11226.8 11383.3 11536.5 11550.8 11570.4 11772.1 11905.2 7039.2 8506.5 8543.0 8609.9 8678.3 8540.3 10786.4 10986.2 11916.8 11918.9 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | [#3] mootools.html Cycles:180 Average:5999.12 Median:4882.73 stddev:3379.48 (69.2%) stddev-sans-first:3371.91 10:03:01 INFO - PROCESS | 1666 | Values: 1481.9 1485.3 1526.1 1866.6 2035.1 8426.7 9087.7 9192.6 9197.2 9214.9 7381.5 9321.8 9327.8 9328.3 9336.0 2163.9 2512.6 2571.2 2572.7 2575.4 2447.1 2534.6 2542.7 2545.2 2549.1 2541.1 2570.4 2586.3 2589.9 2592.9 2551.6 2583.6 2590.0 2591.0 2597.9 2542.8 2582.9 2590.6 2592.4 2601.9 9113.0 9469.4 9527.2 9536.6 9555.7 5676.1 5839.3 5873.1 5890.3 5901.3 3533.1 3625.9 3681.4 3702.9 3708.4 994.7 1036.2 1038.4 1039.8 1039.8 12559.1 12645.4 12807.6 12848.6 12867.8 4813.7 4902.5 4942.2 4986.2 5016.5 10057.6 10058.6 10060.8 10070.9 10083.4 5716.4 5763.1 5776.1 5776.6 5786.1 4746.8 4863.0 5134.0 5305.8 5338.1 4051.9 4080.2 4131.4 4173.1 4179.2 1272.4 1286.1 1288.3 1298.9 1299.5 913.8 944.4 953.3 970.7 1002.2 5982.3 6098.4 6219.7 6228.8 6309.9 480.4 484.4 492.5 890.5 1025.3 3922.1 3994.9 4028.3 4044.6 4083.3 10816.4 10949.5 11290.4 11298.2 11312.1 11163.6 11285.4 11453.7 11455.0 11459.0 7389.3 8636.9 8793.3 8793.7 8920.6 6021.3 6046.5 6073.0 6084.9 6136.3 10083.1 10260.1 10430.8 10443.5 10454.5 10295.7 10626.6 10645.9 10647.5 10889.0 7223.1 7379.2 7697.4 8005.0 8139.7 8780.5 8780.7 8834.2 8838.2 8869.3 7674.5 7752.8 7811.5 7885.9 7915.8 7553.5 7598.5 7629.5 7741.0 7770.2 7318.2 7369.9 7378.8 7475.6 7481.7 5034.6 5037.9 5128.1 5241.5 5242.8 5136.4 5142.0 5240.5 5374.5 5433.5 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | [#4] prototype.html Cycles:180 Average:5754.32 Median:6425.95 stddev:1850.21 (28.8%) stddev-sans-first:1809.97 10:03:01 INFO - PROCESS | 1666 | Values: 324.9 331.3 338.9 341.2 341.4 6318.7 6476.1 6478.7 6479.7 6524.6 6058.8 6360.3 6536.8 6543.1 6590.3 4886.1 4900.1 4912.3 4921.8 4923.1 4666.6 4722.5 4836.3 4893.4 4910.9 4794.6 4840.3 4869.1 4879.1 4879.7 4866.0 4872.1 4875.2 4878.6 4915.7 4212.6 4215.6 4218.5 4221.2 4223.8 6354.9 6360.3 6362.8 6374.7 6375.8 5741.9 5758.1 5758.5 5768.7 5768.8 5611.4 6987.8 6989.9 7013.4 7019.5 3695.9 4563.3 4674.2 4685.9 4706.0 5588.8 7192.7 7414.6 7425.7 7440.6 4627.2 4632.8 4635.8 4643.2 4647.0 4652.6 4822.0 4822.4 4878.4 4909.0 4099.7 4956.4 5401.4 5630.4 5698.1 7025.9 7044.5 7058.1 7078.1 7081.8 1558.7 1623.9 1656.7 1681.7 1683.2 1015.7 1026.2 1042.4 1044.8 1047.7 5931.2 6180.4 6535.1 6674.7 6681.4 7148.1 7276.1 7323.7 7441.1 7466.3 5477.9 6660.1 6776.5 6786.8 6790.1 3861.9 3909.8 3920.2 3922.1 3923.4 7758.7 7766.5 7778.1 7793.8 7794.9 7300.9 7500.2 7542.5 7571.6 7604.4 7686.4 7730.9 7734.0 7744.1 7799.8 6015.8 7401.7 7404.7 7410.8 7418.1 7246.1 7248.0 7251.4 7260.0 7274.9 7417.1 7743.1 7765.5 7812.3 7830.4 4433.7 4588.0 4687.9 4689.7 4697.5 6555.7 6985.5 7114.1 7199.2 7350.2 5711.3 7119.0 7141.7 7152.6 7153.3 7131.6 7138.2 7146.8 7148.8 7154.1 6673.8 6961.9 6969.1 6978.4 6979.5 5735.6 7038.9 7074.0 7096.9 7103.3 6944.3 6962.2 6972.2 6973.7 6980.9 10:03:01 INFO - PROCESS | 1666 | 10:03:01 INFO - PROCESS | 1666 | [#5] yui.html Cycles:180 Average:17736.78 Median:569.41 stddev:24375.90 (4280.9%) stddev-sans-first:24410.73 10:03:01 INFO - PROCESS | 1666 | Values: 704.0 743.5 766.0 780.5 789.1 15292.8 15632.2 15633.5 15650.7 15658.0 12277.3 12440.5 12443.6 12443.9 12464.8 770.1 792.1 930.7 956.2 959.1 961.4 965.0 973.9 974.6 978.5 824.8 1031.1 1036.9 1038.9 1040.3 1001.7 1025.2 1026.1 1027.2 1027.3 766.1 766.4 768.8 770.8 771.9 15430.6 15500.8 15507.4 15507.8 15524.3 12921.0 13202.7 13246.9 13318.0 13533.2 10358.2 10398.6 10458.3 10705.0 10782.7 3557.3 3601.4 3618.8 3622.8 3699.7 61586.0 79516.0 80319.6 85213.0 85412.6 7844.6 9662.8 9795.9 9985.6 10016.9 22904.9 22952.8 22962.5 22964.2 23013.0 7650.3 7785.6 7877.9 8047.8 8294.3 4308.2 4317.7 4327.9 4367.4 4620.7 3737.2 3786.4 3797.3 3801.1 3813.2 530.9 572.8 614.3 687.6 702.4 586.1 587.7 607.2 622.9 629.3 6958.9 8017.3 8116.2 8130.5 8288.9 545.9 546.7 566.0 598.7 637.4 3829.7 3876.3 3889.5 3941.8 3984.3 82206.0 82651.0 83002.2 83120.6 83526.7 73819.3 74292.6 76321.0 76493.9 77689.4 67652.6 67888.0 68707.6 68817.0 69134.0 7660.6 7807.0 7808.9 7820.0 7837.8 48052.8 48312.6 48921.0 49236.3 49919.0 60136.0 62936.7 65141.7 65183.0 66913.7 9607.7 9831.8 10017.2 10090.6 10141.1 10724.6 10883.5 11005.0 11271.2 11334.8 9669.4 11155.6 11405.4 11520.4 11594.7 10970.2 11237.2 11501.7 11522.2 11585.3 11002.3 11065.3 11186.6 11407.1 11410.1 9399.4 9548.3 9573.5 9635.1 9725.2 9511.8 9666.8 9697.8 9775.5 9783.5 10:03:01 INFO - PROCESS | 1666 | -------- Summary: end -------- 10:03:01 INFO - PROCESS | 1666 | 10:03:02 INFO - TEST-INFO | 1666: exit 0 10:03:02 INFO - TEST-OK | dromaeo_css | took 1114092ms 10:03:02 INFO - TEST-START | kraken 10:03:02 INFO - operating with platform_type : mac_ 10:03:02 INFO - Initialising browser for kraken test... 10:03:02 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmphlOUmF/profile http://localhost:49272/getInfo.html) 10:03:04 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 10:03:04 INFO - PROCESS | 1671 | colorDepth:24 10:03:04 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 10:03:04 INFO - PROCESS | 1671 | __metrics 10:03:04 INFO - PROCESS | 1671 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:03:04 ERROR - PROCESS | 1671 | JavaScript error: resource://app/modules/AboutNewTab.jsm, line 36: TypeError: this.pageListener is null 10:03:05 INFO - TEST-INFO | 1671: exit 0 10:03:05 INFO - Browser initialized. 10:03:05 INFO - Running cycle 1/1 for kraken test... 10:03:05 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/T/tmphlOUmF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:03:16 INFO - PROCESS | 1674 | RSS: Main: 161656832 10:03:16 INFO - PROCESS | 1674 | 10:03:19 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 10:03:20 INFO - PROCESS | 1674 | RSS: Main: 223854592 10:03:20 INFO - PROCESS | 1674 | 10:03:22 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 10:03:23 INFO - PROCESS | 1674 | RSS: Main: 239480832 10:03:23 INFO - PROCESS | 1674 | 10:03:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 10:03:27 INFO - PROCESS | 1674 | RSS: Main: 324833280 10:03:27 INFO - PROCESS | 1674 | 10:03:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 10:03:29 INFO - PROCESS | 1674 | RSS: Main: 231260160 10:03:29 INFO - PROCESS | 1674 | 10:03:32 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:03:32 INFO - PROCESS | 1674 | RSS: Main: 231899136 10:03:32 INFO - PROCESS | 1674 | 10:03:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 10:03:35 INFO - PROCESS | 1674 | RSS: Main: 268406784 10:03:35 INFO - PROCESS | 1674 | 10:03:38 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 10:03:39 INFO - PROCESS | 1674 | RSS: Main: 271527936 10:03:39 INFO - PROCESS | 1674 | 10:03:42 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 10:03:42 INFO - PROCESS | 1674 | RSS: Main: 268587008 10:03:42 INFO - PROCESS | 1674 | 10:03:44 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:03:44 INFO - PROCESS | 1674 | RSS: Main: 260763648 10:03:44 INFO - PROCESS | 1674 | 10:03:46 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:03:47 INFO - PROCESS | 1674 | RSS: Main: 268017664 10:03:47 INFO - PROCESS | 1674 | 10:03:49 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:03:50 INFO - PROCESS | 1674 | RSS: Main: 271114240 10:03:50 INFO - PROCESS | 1674 | 10:03:52 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:03:53 INFO - PROCESS | 1674 | RSS: Main: 278233088 10:03:53 INFO - PROCESS | 1674 | 10:03:56 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:03:56 INFO - PROCESS | 1674 | RSS: Main: 275501056 10:03:56 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 10:03:58 INFO - PROCESS | 1674 | RSS: Main: 337301504 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | __start_tp_report 10:03:58 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 10:03:58 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 10:03:58 INFO - PROCESS | 1674 | |i|pagename|runs| 10:03:58 INFO - PROCESS | 1674 | |0;ai-astar;111;112;119;112;111;110;112;112;111;132 10:03:58 INFO - PROCESS | 1674 | |1;audio-beat-detection;154;119;139;160;119;141;116;150;153;118 10:03:58 INFO - PROCESS | 1674 | |2;audio-dft;185;187;186;182;185;185;188;184;186;184 10:03:58 INFO - PROCESS | 1674 | |3;audio-fft;69;78;72;155;56;55;57;67;131;55 10:03:58 INFO - PROCESS | 1674 | |4;audio-oscillator;93;90;141;79;86;86;127;79;76;127 10:03:58 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;85;88;84;88;83;87;84;88;84 10:03:58 INFO - PROCESS | 1674 | |6;imaging-darkroom;92;92;92;94;92;93;93;93;93;92 10:03:58 INFO - PROCESS | 1674 | |7;imaging-desaturate;86;84;84;94;145;131;84;88;87;85 10:03:58 INFO - PROCESS | 1674 | |8;json-parse-financial;59;61;63;62;62;61;63;81;58;57 10:03:58 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;61;65;64;71;74;70;67;66;65;67 10:03:58 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;81;92;101;101;100;79;80;78;81;83 10:03:58 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;141;147;136;127;125;128;142;135;147;144 10:03:58 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;161;164;162;162;156;161;161;169;158;160 10:03:58 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;69;67;67;67;66;67;66;69;68;72 10:03:58 INFO - PROCESS | 1674 | __end_tp_report 10:03:58 INFO - PROCESS | 1674 | __start_cc_report 10:03:58 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1885 10:03:58 INFO - PROCESS | 1674 | __end_cc_report 10:03:58 INFO - PROCESS | 1674 | __startTimestamp1467997438889__endTimestamp 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | ------- Summary: start ------- 10:03:58 INFO - PROCESS | 1674 | Number of tests: 14 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:114.20 Median:112.00 stddev:6.73 (6.0%) stddev-sans-first:7.04 10:03:58 INFO - PROCESS | 1674 | Values: 111.0 112.0 119.0 112.0 111.0 110.0 112.0 112.0 111.0 132.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:136.90 Median:145.50 stddev:17.36 (11.9%) stddev-sans-first:17.28 10:03:58 INFO - PROCESS | 1674 | Values: 154.0 119.0 139.0 160.0 119.0 141.0 116.0 150.0 153.0 118.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.50 stddev:1.69 (0.9%) stddev-sans-first:1.79 10:03:58 INFO - PROCESS | 1674 | Values: 185.0 187.0 186.0 182.0 185.0 185.0 188.0 184.0 186.0 184.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:79.50 Median:62.00 stddev:34.86 (56.2%) stddev-sans-first:36.77 10:03:58 INFO - PROCESS | 1674 | Values: 69.0 78.0 72.0 155.0 56.0 55.0 57.0 67.0 131.0 55.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:98.40 Median:82.50 stddev:23.83 (28.9%) stddev-sans-first:25.20 10:03:58 INFO - PROCESS | 1674 | Values: 93.0 90.0 141.0 79.0 86.0 86.0 127.0 79.0 76.0 127.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:86.00 Median:87.50 stddev:2.21 (2.5%) stddev-sans-first:2.06 10:03:58 INFO - PROCESS | 1674 | Values: 89.0 85.0 88.0 84.0 88.0 83.0 87.0 84.0 88.0 84.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:92.60 Median:93.00 stddev:0.70 (0.8%) stddev-sans-first:0.71 10:03:58 INFO - PROCESS | 1674 | Values: 92.0 92.0 92.0 94.0 92.0 93.0 93.0 93.0 93.0 92.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:85.50 stddev:22.17 (25.9%) stddev-sans-first:23.16 10:03:58 INFO - PROCESS | 1674 | Values: 86.0 84.0 84.0 94.0 145.0 131.0 84.0 88.0 87.0 85.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:62.00 stddev:6.75 (10.9%) stddev-sans-first:7.03 10:03:58 INFO - PROCESS | 1674 | Values: 59.0 61.0 63.0 62.0 62.0 61.0 63.0 81.0 58.0 57.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:67.00 Median:67.00 stddev:3.77 (5.6%) stddev-sans-first:3.32 10:03:58 INFO - PROCESS | 1674 | Values: 61.0 65.0 64.0 71.0 74.0 70.0 67.0 66.0 65.0 67.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:87.60 Median:80.50 stddev:9.80 (12.2%) stddev-sans-first:10.10 10:03:58 INFO - PROCESS | 1674 | Values: 81.0 92.0 101.0 101.0 100.0 79.0 80.0 78.0 81.0 83.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:137.20 Median:141.50 stddev:8.30 (5.9%) stddev-sans-first:8.69 10:03:58 INFO - PROCESS | 1674 | Values: 141.0 147.0 136.0 127.0 125.0 128.0 142.0 135.0 147.0 144.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:161.40 Median:161.50 stddev:3.47 (2.1%) stddev-sans-first:3.68 10:03:58 INFO - PROCESS | 1674 | Values: 161.0 164.0 162.0 162.0 156.0 161.0 161.0 169.0 158.0 160.0 10:03:58 INFO - PROCESS | 1674 | 10:03:58 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.80 Median:67.50 stddev:1.81 (2.7%) stddev-sans-first:1.87 10:03:58 INFO - PROCESS | 1674 | Values: 69.0 67.0 67.0 67.0 66.0 67.0 66.0 69.0 68.0 72.0 10:03:58 INFO - PROCESS | 1674 | -------- Summary: end -------- 10:03:58 INFO - PROCESS | 1674 | 10:03:59 INFO - TEST-INFO | 1674: exit 0 10:03:59 INFO - TEST-OK | kraken | took 57431ms 10:03:59 INFO - SUITE-END | took 1171s 10:04:00 INFO - Completed test suite (00:19:31) 10:04:00 INFO - javascript benchmark 10:04:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3035.8482075896204, 3549.325628130655, 3633.091727068233, 3668.3396988542054, 3686.428603566448, 5862.384449632791, 5892.08672655738, 5897.144913987469, 5908.970455147723, 5923.022701254288, 5968.67172305523, 5991.520678345732, 6011.789587364443, 6014.5489088318445, 6016.398360163981, 5275.736213189344, 5340.225667278242, 5379.596530260226, 5402.594805389612, 5431.456854314576, 5378.731063446812, 5448.210009548613, 5449.100898351767, 5449.7275136243225, 5453.64551304164, 5356.54469370104, 5417.45825417459, 5436.130219164935, 5444.537214336786, 5459.808906688267, 5423.186522021709, 5443.891122177574, 5449.3188351456065, 5461.5357694596005, 5485.314335708036, 5229.058769421503, 5251.737413129366, 5377.919331210035, 5386.057439947994, 5391.541718953894, 4769.95230047702, 5692.5161361284345, 5782.268774151062, 5811.302643682785, 5819.7963071292725, 6292.653904035276, 6315.589486683397, 6326.335734747877, 6327.24073111225, 6328.1773369462135, 5850.561207909382, 6235.968820155916, 6273.905891411632, 6362.968185159091, 6373.23521177457, 5988.131720900445, 6026.6986650667295, 6057.697115144225, 6058.939410605925, 6064.332923378469, 6428.678566071678, 6431.646259455775, 6437.066625339299, 6440.54916155874, 6454.354564543508, 6187.16473276111, 6256.217972753406, 6339.936600633934, 6377.74489020434, 6381.776637817747, 6326.0, 6339.556231063873, 6348.841278968062, 6355.046742988497, 6355.428011478989, 6250.6874656267, 6288.842778930563, 6317.241930968221, 6323.30443651198, 6330.968345158337, 6224.813255602339, 6245.219347581552, 6319.842003949938, 6331.176946996953, 6369.777057802955, 5045.621578381636, 5082.923756143661, 5086.694798312114, 5095.337606111256, 5096.643234973591, 4545.568171023748, 4579.427571553556, 4585.10590434862, 4595.356650068993, 4599.816007359735, 6116.847078823065, 6206.968965155235, 6207.131001659771, 6214.751409943552, 6225.159603453566, 6375.10748495211, 6397.424231819158, 6402.167718196604, 6408.839779005562, 6411.807645770635, 6015.849603759941, 6116.724747386378, 6168.383161683885, 6182.536309776785, 6189.50483961282, 5575.944240557624, 5590.86022849432, 5651.689157096399, 5660.660360378308, 5675.744591493392, 5494.478024587658, 6539.149910511603, 6557.9344206557325, 6579.046038324512, 6582.341765823475, 6275.529335299962, 6327.462165715966, 6380.03604935067, 6404.871902562016, 6410.326915673787, 6567.343265673395, 6623.304553021863, 6624.337566243337, 6627.5360724750735, 6637.734490620321, 6306.684665766602, 6307.905381419374, 6318.4313411793155, 6324.0, 6332.2717887441695, 6134.130457564626, 6241.812745617639, 6277.529185311211, 6350.301466838551, 6366.904496432465, 6311.0217916223055, 6312.715927783169, 6333.334999824952, 6343.492520598434, 6349.238091429058, 6326.335734747785, 6363.649999250086, 6369.14016607752, 6373.936260637334, 6381.553291269566, 6130.5402094842175, 6162.106494558354, 6200.534959878118, 6211.782587609412, 6280.497560195265, 6145.84635384119, 6280.5289603279025, 6287.497000239879, 6297.811065668037, 6300.464460520907, 6145.617236121837, 6185.165002724754, 6221.222347206599, 6266.624002559861, 6280.748770049329], "value": 5886.188955790964, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [529.4017759931277, 534.5509771791694, 567.4297331182164, 576.0236399303182, 615.375393975115, 26536.867315663418, 32805.195714235706, 32818.50772238418, 32871.0, 32973.34053318932, 35887.64112358876, 41080.58919410813, 41998.79000604993, 42000.0, 42186.15627687444, 9659.951700241489, 9779.511024448784, 9811.705648830512, 9812.46031468269, 9825.410475371466, 9192.034836342187, 9556.232097062066, 9671.516424178799, 9679.983601721824, 9688.46713430761, 11976.221545599552, 12205.145639805218, 12226.633201003984, 12258.387080645976, 12262.509499620004, 11729.765404691901, 11901.940490297493, 11912.404379780975, 11917.22538035025, 11920.582779602759, 11633.59282425111, 11739.941300293443, 11749.412529373583, 11761.647150585497, 11902.58340958071, 39750.60872869465, 39875.80372588827, 39914.201715965515, 40010.399844002364, 40026.79986600077, 3311.8840840570465, 3329.517220003086, 3477.1480987158307, 3543.167355671415, 3655.744097913147, 28862.422751545062, 29507.114786556438, 30783.384332313453, 30857.919972800843, 31325.37349253025, 2761.226856480189, 2776.7778577713734, 2793.2877116335408, 2859.7283258090447, 2861.556458748897, 385471.0, 386026.0, 386886.0, 387209.0, 388438.0, 36965.0, 37905.62094379076, 37969.430458543146, 68677.65661171662, 70112.64943675249, 37063.81468092696, 37179.62820371816, 37202.62797372046, 37215.25569488595, 37326.813365933, 16050.672652465328, 16166.919165404333, 16222.35110595563, 16235.188240587924, 16242.75635865463, 13593.52422665202, 13693.452261909611, 13704.45182192721, 13704.588862334145, 13707.794383084261, 8043.356531477468, 8196.13940536247, 8527.147285271547, 8558.315334773204, 8707.47755134694, 3496.1434448559758, 3553.3781588222473, 3556.76881002734, 3565.037439891267, 3584.1577229351083, 883.8320719063357, 924.9861252081224, 1043.7527155049643, 1069.652362982034, 1124.145649306533, 30711.0, 30803.84598076995, 30972.690273097433, 31392.529112063337, 31427.0, 1158.2760774515928, 1161.936093514848, 1178.3401295274673, 1224.5040758492826, 1240.460399726122, 15662.373505059897, 15670.98138620986, 15688.294026768823, 16003.759943600855, 16023.0, 651958.0, 652489.0, 655647.0, 661019.0, 663006.6849665818, 64164.0, 64845.0, 64848.027279590846, 64940.350596494376, 65184.674076629315, 1405.6907480354305, 1444.5955132563108, 1564.929578168985, 1565.3190861975077, 1630.6086539230737, 29258.7074129256, 29730.108096757132, 30743.385132297677, 31017.0694879154, 31297.687023129478, 53331.73334133304, 53662.0, 53678.0, 53696.1945570809, 53877.46122538725, 389539.0, 405678.0, 406702.0, 406921.0, 407472.0, 25276.241712748648, 28183.0, 28305.0, 28619.856900715364, 28805.0, 43885.0, 44056.559434405244, 44777.55222447734, 45768.31347529723, 45851.3122303159, 35010.0, 35748.28503429865, 36139.0, 36192.91421257366, 36529.0, 37548.06129846776, 37674.62325376711, 38141.61858381492, 38291.0, 38384.040398989135, 38852.6114738849, 39544.40683389809, 41093.79453102715, 41255.0, 41396.37905431481, 32303.900246483903, 33858.3228335427, 33900.81347152838, 33919.491207631414, 33956.66043339535, 24872.0, 25301.240962771146, 25402.364940875887, 25533.872330638227, 25749.613755793303], "value": 18938.473722787447, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [419.2913975381606, 421.46473978047896, 423.0924666590164, 427.64291816333343, 434.88692939835653, 20526.178952841867, 20754.169833206655, 21031.894840525776, 21446.356609301725, 21510.784892151074, 20589.897050514763, 20658.38024859253, 21822.90885455725, 21832.8908355458, 21855.016524256407, 5720.084786434171, 6162.22972128484, 6163.414475624809, 6163.599366041193, 6167.044108163242, 6006.639601623895, 6019.277686677605, 6114.1745864308195, 6123.357047510014, 6131.662758548278, 5983.970080149593, 6262.12330273762, 6264.56148069633, 6267.717952692117, 6268.0, 5920.230370051909, 6131.7240724167295, 6194.318624951252, 6195.473384762301, 6204.565680402374, 6083.30042045166, 6087.4825639820665, 6100.084987251903, 6103.267607887082, 6104.572679912408, 11455.484503197376, 13766.31168441574, 13835.51575694856, 14345.569632911029, 14353.425862965469, 2281.726192856868, 2382.60686986647, 2539.771420572139, 2615.882285297166, 2859.985700071507, 10231.386116833013, 10764.78470430587, 14565.0, 15118.941674082818, 15934.442294519753, 1736.8089510153873, 1782.6523827853573, 1821.2068982694623, 1827.0316732131992, 1895.6966885298425, 65136.0, 67139.99290010653, 108032.0, 127245.36377318054, 127350.08974865385, 11885.88114118865, 12013.099017573717, 12134.635960921187, 12136.757264854654, 12167.0, 14016.088954217943, 14357.21035343066, 14374.281285935871, 14401.351939162763, 14410.42358305656, 7575.886361704579, 9138.406003609743, 9185.265178785814, 9272.0, 9359.064093590723, 9183.357164998386, 9184.678536251198, 9206.757087793196, 9292.303077269096, 9300.953495232481, 3982.0, 4765.7140571565815, 4819.7108173509705, 4830.637702172351, 4839.322494850724, 1963.106786412179, 2006.6588679924448, 2007.5483016321502, 2021.6158929803291, 2029.939101826918, 923.1368670293298, 946.2997381937455, 1004.3672486333563, 1088.7713580148259, 1113.276370359273, 20121.0, 20180.19279228844, 20447.0, 20723.792762072186, 20815.79184208169, 986.1026465915982, 1008.7881544875659, 1016.501914062119, 1037.8656457498312, 1081.5673730507704, 10549.947250263853, 11082.279651822606, 11205.495752691148, 11648.009919156797, 11933.820992684943, 421220.0, 641492.0, 642781.0, 646314.0, 651729.0, 121262.0, 123710.0, 129522.35238823746, 129966.0, 136016.31991839976, 1007.1640538353149, 1078.0405439159, 1096.413418820943, 1097.1332647208617, 1097.9725506862233, 18209.726854097466, 18260.72608910894, 18280.360187393377, 18657.346992855728, 18892.33876814305, 54119.72940135274, 56439.15341269802, 56786.0, 56895.0, 57573.4242657568, 70247.0, 77433.61283193548, 78752.60623697074, 83751.0, 86220.0, 7326.6702998364135, 8395.538245396561, 8424.11546787603, 8501.107383724868, 8768.956155219183, 11475.196736228718, 12328.383580820744, 12369.763023697558, 12516.436760345625, 13051.673708157372, 7139.8572028560175, 10812.297200681931, 11051.336919784839, 11263.718407039889, 11317.377544235145, 10051.949740251252, 10849.837252441062, 11205.495752690986, 11226.831597526207, 11383.317000979967, 11536.480858361561, 11550.768984620428, 11570.363630000431, 11772.058235341323, 11905.226160299553, 7039.225685174524, 8506.489610623383, 8543.017552981239, 8609.913900861162, 8678.349123815866, 8540.27407670355, 10786.406747628957, 10986.230963832775, 11916.821247681464, 11918.880811192124], "value": 9716.455433877338, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1481.9184944828035, 1485.3093311610098, 1526.1377321813172, 1866.6266746650674, 2035.053700029487, 8426.747197584074, 9087.681931132413, 9192.63229470822, 9197.172254497094, 9214.861777073349, 7381.48329616927, 9321.766955826091, 9327.766805829857, 9328.253739700825, 9335.95332023339, 2163.8593491423085, 2512.5603019471564, 2571.1643715792347, 2572.7298633643477, 2575.4032499850027, 2447.1068060158004, 2534.6071358939394, 2542.720300766914, 2545.2237067694346, 2549.069589599792, 2541.097910241856, 2570.3702592864774, 2586.2629150692055, 2589.9482010359875, 2592.857392843393, 2551.553478141318, 2583.599542070982, 2589.9741002590017, 2590.961135582968, 2597.857117858517, 2542.7965762738936, 2582.9354266143496, 2590.6243594678854, 2592.3648706066947, 2601.9479610407684, 9112.952010518742, 9469.384490008197, 9527.237820974306, 9536.570854311572, 9555.713328600154, 5676.063449530822, 5839.270091238595, 5873.1190321451695, 5890.263717035371, 5901.321348044987, 3533.063738109403, 3625.8912232633065, 3681.4293784463453, 3702.87039953603, 3708.35103856824, 994.721477986165, 1036.233187441288, 1038.3562191441279, 1039.7660526381503, 1039.8475156426125, 12559.058070644738, 12645.367731613382, 12807.61577152687, 12848.614541563767, 12867.80698289517, 4813.711177329372, 4902.48523904992, 4942.233953737176, 4986.177280748709, 5016.473270306638, 10057.597696092074, 10058.647947321808, 10060.798784024279, 10070.899291006996, 10083.445410502347, 5716.399778023331, 5763.106718458645, 5776.133579963039, 5776.624519406225, 5786.132080188006, 4746.786394612251, 4862.975685121552, 5134.024535338273, 5305.840824775263, 5338.092524270884, 4051.8987025324013, 4080.2451546464, 4131.380292956021, 4173.08192197716, 4179.185058913544, 1272.395612084271, 1286.093304220541, 1288.3171918883006, 1298.8571257161705, 1299.4672184404349, 913.8400779863497, 944.3531181140941, 953.2564599612314, 970.708787363788, 1002.1932344462632, 5982.282126144803, 6098.420650038239, 6219.657918814418, 6228.844278893064, 6309.873802524015, 480.42829033450084, 484.41627838454576, 492.50257240186954, 890.528020149322, 1025.349187807629, 3922.058705910561, 3994.8801535953967, 4028.3353246713964, 4044.5753195914594, 4083.2854250506043, 10816.351018938889, 10949.4525273736, 11290.379029153475, 11298.152638551977, 11312.095032397388, 11163.609273675382, 11285.379304138187, 11453.713657158638, 11455.026322762491, 11458.968692817687, 7389.298016688406, 8636.877205963185, 8793.340499462438, 8793.692220772371, 8920.598573064228, 6021.277446706424, 6046.516278697605, 6073.000797254692, 6084.908726369196, 6136.29432615255, 10083.092521673085, 10260.127889129508, 10430.843537347098, 10443.530041148015, 10454.477276136315, 10295.691129266133, 10626.574937002433, 10645.8935410648, 10647.520861561403, 10889.0199882011, 7223.097112860893, 7379.151397589346, 7697.384209263134, 8004.959975200087, 8139.674413023413, 8780.517071561126, 8780.736577902673, 8834.160754728291, 8838.160374764388, 8869.290456763572, 7674.501157424749, 7752.7674169775, 7811.4922530035365, 7885.921140788748, 7915.84168316642, 7553.546787192786, 7598.544087354777, 7629.54222746637, 7741.0, 7770.222977702185, 7318.158411782713, 7369.852602948018, 7378.778636640909, 7475.551466912003, 7481.70073197066, 5034.647574669738, 5037.874053148701, 5128.102582048201, 5241.475852414728, 5242.790288388422, 5136.409312928911, 5142.0, 5240.528352448298, 5374.543163831118, 5433.483819037185], "value": 4774.954244280654, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.92851572654024, 331.3389787374188, 338.8729226540047, 341.2169071979806, 341.3701720325998, 6318.715657795398, 6476.093346931428, 6478.740850365985, 6479.676016199195, 6524.608523488588, 6058.818235452944, 6360.300366959642, 6536.803895883119, 6543.05125756765, 6590.275069742336, 4886.096072226632, 4900.093482705693, 4912.336834527347, 4921.80312787488, 4923.064617722639, 4666.626669866419, 4722.480527142012, 4836.322914791932, 4893.3638626978445, 4910.926336104943, 4794.592459640935, 4840.298156767267, 4869.074875773609, 4879.1461494238365, 4879.731614761205, 4865.9756701216265, 4872.123017856784, 4875.24433712775, 4878.634102442331, 4915.705057696549, 4212.578742125794, 4215.57844215576, 4218.472690913636, 4221.176870510221, 4223.809928553192, 6354.904676429857, 6360.3321651226415, 6362.840928976766, 6374.681265936684, 6375.808725738235, 5741.942580574224, 5758.078707406795, 5758.481736643722, 5768.711564421762, 5768.769249230026, 5611.382747897769, 6987.7903662889685, 6989.93010069903, 7013.368796808311, 7019.543729657556, 3695.9445608315896, 4563.292689633112, 4674.182018146811, 4685.8594242172785, 4705.952940470585, 5588.770470496484, 7192.748253811092, 7414.6292685365515, 7425.740099096506, 7440.627968601549, 4627.167109920179, 4632.791524381411, 4635.814567417266, 4643.164230438553, 4647.000894807565, 4652.581267685857, 4822.0, 4822.445418776815, 4878.43897951733, 4908.950910490921, 4099.692523060782, 4956.3804524434445, 5401.405845357008, 5630.380658127603, 5698.116791897262, 7025.92974070263, 7044.506884518137, 7058.082449281561, 7078.115235595551, 7081.787546373617, 1558.7428074367601, 1623.9350425982827, 1656.6521030583713, 1681.7141086015406, 1683.2257161705509, 1015.6952914125733, 1026.2046913641987, 1042.3849928542197, 1044.7753732947301, 1047.675220681591, 5931.169636250928, 6180.443760061616, 6535.117759102461, 6674.733010679615, 6681.432078273304, 7148.142222933177, 7276.090488688914, 7323.7436689715605, 7441.069866266716, 7466.328030477283, 5477.931803298318, 6660.100886380276, 6776.525643204928, 6786.796396108125, 6790.083338749305, 3861.864834730771, 3909.7654140751647, 3920.157166209279, 3922.0979174789386, 3923.431102490123, 7758.650860711282, 7766.456348055694, 7778.105517865405, 7793.844123117506, 7794.883076753853, 7300.926990730131, 7500.212477689764, 7542.547447153188, 7571.583562903983, 7604.353629941406, 7686.423518236154, 7730.884036739566, 7734.03324584427, 7744.148143704188, 7799.844003119792, 6015.849603759941, 7401.666924988496, 7404.703811847466, 7410.777221758565, 7418.146913104846, 7246.130464344313, 7248.021517095125, 7251.419886408969, 7260.0, 7274.854502909806, 7417.109946806418, 7743.148253691977, 7765.495242809199, 7812.3359514439635, 7830.41271904598, 4433.733975961452, 4588.0, 4687.882802929817, 4689.695169813951, 4697.5302469754115, 6555.672216389257, 6985.545939513915, 7114.075170227838, 7199.2440793715905, 7350.154732205651, 5711.257536520392, 7119.0, 7141.714331426685, 7152.606606636685, 7153.320434558708, 7131.572105673677, 7138.179109402277, 7146.785596432115, 7148.821279468055, 7154.105736782903, 6673.83315417099, 6961.920902260158, 6969.0940177776565, 6978.441724662116, 6979.5114341997605, 5735.598508104393, 7038.929610703827, 7073.964630176816, 7096.858062838817, 7103.2541583133025, 6944.305569443218, 6962.23415424313, 6972.163340398982, 6973.721051157897, 6980.860382792417], "value": 5139.992519755965, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [703.9859202815943, 743.5352904434721, 766.0007380736278, 780.5199802121697, 789.1398375770411, 15292.84707152928, 15632.218389080557, 15633.452829150981, 15650.686986260282, 15658.0, 12277.324747138904, 12440.502379904816, 12443.626691199255, 12443.875561244386, 12464.81302780459, 770.0912922751152, 792.0890975378303, 930.7114794413753, 956.2350119904083, 959.0984474593894, 961.3510880155902, 964.9903500965006, 973.9366941148838, 974.6491263145298, 978.5400861595039, 824.8185399212164, 1031.056583226347, 1036.9222308326869, 1038.9064984151426, 1040.2770074798018, 1001.6894762623574, 1025.2208321675505, 1026.0611540440489, 1027.2347101409423, 1027.342500799489, 766.1074847802304, 766.4251811141644, 768.8077980504873, 770.803445121496, 771.9150893401721, 15430.614234644112, 15500.844991550053, 15507.379704811794, 15507.767383489258, 15524.301406436738, 12921.0, 13202.66993325165, 13246.853185291073, 13318.0, 13533.18800871941, 10358.171346292278, 10398.584056637725, 10458.267921245515, 10705.0, 10782.73043173927, 3557.324108419392, 3601.441776524643, 3618.7647802892993, 3622.800745958971, 3699.7040236780995, 61586.0, 79516.0, 80319.5984020082, 85213.0, 85412.57293713492, 7844.64699088531, 9662.75843103914, 9795.853062204073, 9985.550650220757, 10016.899831001596, 22904.885475572515, 22952.770472295397, 22962.540749184922, 22964.19625313106, 23013.0, 7650.311471967439, 7785.64964576584, 7877.921220787834, 8047.798805029921, 8294.336453083739, 4308.224519586442, 4317.7193482423545, 4327.913441731148, 4367.432233809584, 4620.653450991189, 3737.1591391936595, 3786.3563194257044, 3797.3354662933875, 3801.1447424329303, 3813.1801662642665, 530.9044372013075, 572.7995201679379, 614.2628845385556, 687.6458623808743, 702.4134847402374, 586.1178925716769, 587.7472686744741, 607.2106261859565, 622.9221347331584, 629.329763801559, 6958.8956165657555, 8017.318527925192, 8116.188381161836, 8130.471519351223, 8288.875666865002, 545.8771776350289, 546.710243570908, 566.0207840435986, 598.7066337494597, 637.3594537489886, 3829.6744776694295, 3876.2635099331037, 3889.474920885644, 3941.8423263069158, 3984.3425834736945, 82206.0, 82651.0, 83002.16997829944, 83120.58439707763, 83526.74709879357, 73819.26180738123, 74292.62853685806, 76321.0, 76493.85259221116, 77689.44621107547, 67652.64694706078, 67888.0, 68707.62584748377, 68817.0, 69134.0, 7660.616969151632, 7807.0, 7808.921910781047, 7820.0, 7837.7648670539975, 48052.7597362025, 48312.55062348135, 48921.0, 49236.2614560789, 49919.0, 60136.0, 62936.74126517352, 65141.67429162824, 65183.0, 66913.66172676616, 9607.66618969032, 9831.754206144904, 10017.198624109908, 10090.596376144871, 10141.138003269805, 10724.571017159225, 10883.510242038968, 11005.0, 11271.154663400113, 11334.829977550178, 9669.371490853073, 11155.55377784912, 11405.372704501, 11520.366379849189, 11594.6521604352, 10970.177236707119, 11237.157213208879, 11501.712457188638, 11522.207676493877, 11585.304881707125, 11002.33985960845, 11065.336079835235, 11186.608468703882, 11407.144464165054, 11410.14423918226, 9399.389039712396, 9548.283878708986, 9573.473458959545, 9635.13283804461, 9725.221982241263, 9511.762205944906, 9666.7583310415, 9697.806043878942, 9775.511224438891, 9783.510824458606], "value": 6920.535450395882, "unit": "score"}], "name": "dromaeo_css", "value": 7541.633449887205, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 112.0, 119.0, 112.0, 111.0, 110.0, 112.0, 112.0, 111.0, 132.0], "value": 114.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 119.0, 139.0, 160.0, 119.0, 141.0, 116.0, 150.0, 153.0, 118.0], "value": 136.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 186.0, 182.0, 185.0, 185.0, 188.0, 184.0, 186.0, 184.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 72.0, 155.0, 56.0, 55.0, 57.0, 67.0, 131.0, 55.0], "value": 79.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 141.0, 79.0, 86.0, 86.0, 127.0, 79.0, 76.0, 127.0], "value": 98.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 88.0, 84.0, 88.0, 83.0, 87.0, 84.0, 88.0, 84.0], "value": 86.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 92.0, 94.0, 92.0, 93.0, 93.0, 93.0, 93.0, 92.0], "value": 92.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 94.0, 145.0, 131.0, 84.0, 88.0, 87.0, 85.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 61.0, 63.0, 62.0, 62.0, 61.0, 63.0, 81.0, 58.0, 57.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 65.0, 64.0, 71.0, 74.0, 70.0, 67.0, 66.0, 65.0, 67.0], "value": 67.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 92.0, 101.0, 101.0, 100.0, 79.0, 80.0, 78.0, 81.0, 83.0], "value": 87.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 147.0, 136.0, 127.0, 125.0, 128.0, 142.0, 135.0, 147.0, 144.0], "value": 137.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 164.0, 162.0, 162.0, 156.0, 161.0, 161.0, 169.0, 158.0, 160.0], "value": 161.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 67.0, 66.0, 67.0, 66.0, 69.0, 68.0, 72.0], "value": 67.8, "unit": "score"}], "name": "kraken", "value": 1473.3, "alertThreshold": 2.0}]} 10:04:00 INFO - Return code: 0 10:04:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 10:04:00 ERROR - # TBPL SUCCESS # 10:04:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:04:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:04:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:04:00 INFO - 2016-07-08 10:04:00.223 screenresolution[1678:10243] starting screenresolution argv=screenresolution get 10:04:00 INFO - 2016-07-08 10:04:00.226 screenresolution[1678:10243] Display 0: 1600x1200x32@60 10:04:00 INFO - 2016-07-08 10:04:00.232 screenresolution[1679:10246] starting screenresolution argv=screenresolution list 10:04:00 INFO - Available Modes on Display 0 10:04:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:04:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:04:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:04:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:04:00 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:04:00 INFO - Intel Iris: 10:04:00 INFO - Chipset Model: Intel Iris 10:04:00 INFO - Type: GPU 10:04:00 INFO - Bus: Built-In 10:04:00 INFO - VRAM (Dynamic, Max): 1536 MB 10:04:00 INFO - Vendor: Intel (0x8086) 10:04:00 INFO - Device ID: 0x0a2e 10:04:00 INFO - Revision ID: 0x0009 10:04:00 INFO - Displays: 10:04:00 INFO - Display: 10:04:00 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:04:00 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:04:00 INFO - Display Serial Number: 1600x1200 60 10:04:00 INFO - Main Display: Yes 10:04:00 INFO - Mirror: Off 10:04:00 INFO - Online: Yes 10:04:00 INFO - Rotation: Supported 10:04:00 INFO - Return code: 0 10:04:00 INFO - Running post-action listener: _resource_record_post_action 10:04:00 INFO - Running post-run listener: _resource_record_post_run 10:04:01 INFO - Total resource usage - Wall time: 1195s; CPU: 52.0%; Read bytes: 32689664; Write bytes: 496508928; Read time: 378; Write time: 1574 10:04:01 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 180912640; Write bytes: 178046976; Read time: 16365; Write time: 383 10:04:01 INFO - run-tests - Wall time: 1174s; CPU: 52.0%; Read bytes: 31776256; Write bytes: 298031104; Read time: 365; Write time: 1132 10:04:01 INFO - Running post-run listener: _upload_blobber_files 10:04:01 INFO - Blob upload gear active. 10:04:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:04:01 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 10:04:01 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'] 10:04:01 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 10:04:01 INFO - (blobuploader) - INFO - Open directory for files ... 10:04:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:04:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:04:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:03 INFO - (blobuploader) - INFO - Done attempting. 10:04:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:04:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:04:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:04 INFO - (blobuploader) - INFO - Done attempting. 10:04:04 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:04 INFO - Return code: 0 10:04:04 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:04 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:04 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5922555c5ef23f1eb7f276877804463292f42d47c53833c8b3a7c3a859236da3da940f0129cd59f66bc28a942f4a9f2328b79d6189edf30483ca8849cfae03f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee7b07d640d5fa2b5c18b77bd7d8a668cdaa43e03ff50afca93cd5ff266b4f8bb13bcfc6686f7d9bbff9040f4736fe4631edb4601a4d7c03d7298574f79ca295"} 10:04:04 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:04 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:04 INFO - Contents: 10:04:04 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/5922555c5ef23f1eb7f276877804463292f42d47c53833c8b3a7c3a859236da3da940f0129cd59f66bc28a942f4a9f2328b79d6189edf30483ca8849cfae03f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee7b07d640d5fa2b5c18b77bd7d8a668cdaa43e03ff50afca93cd5ff266b4f8bb13bcfc6686f7d9bbff9040f4736fe4631edb4601a4d7c03d7298574f79ca295"} 10:04:04 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:04 INFO - Copying logs to upload dir... 10:04:04 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:04 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.657161 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2016-07-08 10:04:04.075396) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 10:04:04.078436) ========= 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.u1QO8zoalZ/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.k25EpZRgze/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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/5922555c5ef23f1eb7f276877804463292f42d47c53833c8b3a7c3a859236da3da940f0129cd59f66bc28a942f4a9f2328b79d6189edf30483ca8849cfae03f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee7b07d640d5fa2b5c18b77bd7d8a668cdaa43e03ff50afca93cd5ff266b4f8bb13bcfc6686f7d9bbff9040f4736fe4631edb4601a4d7c03d7298574f79ca295"} build_url:https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/artifacts/public/build/firefox-48.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011185 build_url: 'https://queue.taskcluster.net/v1/task/fHydg6otRG2ZPAWvgsE2HA/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/5922555c5ef23f1eb7f276877804463292f42d47c53833c8b3a7c3a859236da3da940f0129cd59f66bc28a942f4a9f2328b79d6189edf30483ca8849cfae03f9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ee7b07d640d5fa2b5c18b77bd7d8a668cdaa43e03ff50afca93cd5ff266b4f8bb13bcfc6686f7d9bbff9040f4736fe4631edb4601a4d7c03d7298574f79ca295"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-08 10:04:04.112659) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 10:04:04.112955) ========= 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.u1QO8zoalZ/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.k25EpZRgze/Listeners TMPDIR=/var/folders/f6/kjqp0l7n7cb307nv1hq0lrf400000w/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.005119 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-08 10:04:04.161825) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 10:04:04.162125) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-08 10:04:04.162610) ========= ========= Total master_lag: 0.17 =========