builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0011 starttime: 1462533758.41 results: success (0) buildid: 20160506013623 builduid: 3e330e94e72a44aabac68afc380e9ed1 revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.408095) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.408624) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.408901) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.429966) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.430345) ========= 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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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-05-06 04:22:38-- 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-05-06 04:22:38 (184 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.085203 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.533224) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.533567) ========= 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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.035816 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:38.584669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 04:22:38.585017) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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-05-06 04:22:38,658 truncating revision to first 12 chars 2016-05-06 04:22:38,658 Setting DEBUG logging. 2016-05-06 04:22:38,658 attempt 1/10 2016-05-06 04:22:38,658 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/b61ad3a6049c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-05-06 04:22:39,337 unpacking tar archive at: mozilla-aurora-b61ad3a6049c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.921100 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-05-06 04:22:39.590406) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:39.590737) ========= script_repo_revision: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:39.591107) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:39.591367) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-05-06 04:22:39.605164) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-06 04:22:39.605490) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 04:22:39 INFO - MultiFileLogger online at 20160506 04:22:39 in /builds/slave/test 04:22:39 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 04:22:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:22:39 INFO - {'append_to_log': False, 04:22:39 INFO - 'base_work_dir': '/builds/slave/test', 04:22:39 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 04:22:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:22:39 INFO - 'branch': 'Mozilla-Aurora', 04:22:39 INFO - 'buildbot_json_path': 'buildprops.json', 04:22:39 INFO - 'config_files': ('talos/mac_config.py',), 04:22:39 INFO - 'default_actions': ('clobber', 04:22:39 INFO - 'read-buildbot-config', 04:22:39 INFO - 'download-and-extract', 04:22:39 INFO - 'populate-webroot', 04:22:39 INFO - 'create-virtualenv', 04:22:39 INFO - 'install', 04:22:39 INFO - 'run-tests'), 04:22:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:22:39 INFO - 'download_minidump_stackwalk': True, 04:22:39 INFO - 'download_symbols': 'ondemand', 04:22:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:22:39 INFO - 'tooltool.py': '/tools/tooltool.py', 04:22:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:22:39 INFO - '/tools/misc-python/virtualenv.py')}, 04:22:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:22:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:22:39 INFO - 'installer_path': 'installer.exe', 04:22:39 INFO - 'log_level': 'info', 04:22:39 INFO - 'log_name': 'talos', 04:22:39 INFO - 'log_to_console': True, 04:22:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:22:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:22:39 INFO - 'opt_config_files': (), 04:22:39 INFO - 'pip_index': False, 04:22:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:22:39 INFO - 'cmd': ('bash', 04:22:39 INFO - '-c', 04:22:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:22:39 INFO - 'enabled': True, 04:22:39 INFO - 'halt_on_failure': False, 04:22:39 INFO - 'name': 'check_screen_resolution'},), 04:22:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:22:39 INFO - 'cmd': ('bash', 04:22:39 INFO - '-c', 04:22:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:22:39 INFO - 'enabled': True, 04:22:39 INFO - 'halt_on_failure': False, 04:22:39 INFO - 'name': 'check_screen_resolution'},), 04:22:39 INFO - 'run_cmd_checks_enabled': True, 04:22:39 INFO - 'sps_profile': False, 04:22:39 INFO - 'sps_profile_interval': 0, 04:22:39 INFO - 'suite': 'dromaeojs-e10s', 04:22:39 INFO - 'system_bits': '32', 04:22:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:22:39 INFO - 'title': 't-yosemite-r7-0011', 04:22:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:22:39 INFO - 'use_talos_json': True, 04:22:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:22:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:22:39 INFO - 'work_dir': 'build'} 04:22:39 INFO - ##### 04:22:39 INFO - ##### Running clobber step. 04:22:39 INFO - ##### 04:22:39 INFO - Running pre-action listener: _resource_record_pre_action 04:22:39 INFO - Running main action method: clobber 04:22:39 INFO - rmtree: /builds/slave/test/build 04:22:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:22:41 INFO - Running post-action listener: _resource_record_post_action 04:22:41 INFO - ##### 04:22:41 INFO - ##### Running read-buildbot-config step. 04:22:41 INFO - ##### 04:22:41 INFO - Running pre-action listener: _resource_record_pre_action 04:22:41 INFO - Running main action method: read_buildbot_config 04:22:41 INFO - Using buildbot properties: 04:22:41 INFO - { 04:22:41 INFO - "project": "", 04:22:41 INFO - "product": "firefox", 04:22:41 INFO - "script_repo_revision": "production", 04:22:41 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 04:22:41 INFO - "repository": "", 04:22:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 04:22:41 INFO - "buildid": "20160506013623", 04:22:41 INFO - "pgo_build": "False", 04:22:41 INFO - "basedir": "/builds/slave/test", 04:22:41 INFO - "buildnumber": 1, 04:22:41 INFO - "slavename": "t-yosemite-r7-0011", 04:22:41 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 04:22:41 INFO - "platform": "yosemite_r7", 04:22:41 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 04:22:41 INFO - "branch": "mozilla-aurora", 04:22:41 INFO - "repo_path": "releases/mozilla-aurora", 04:22:41 INFO - "revision": "b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f", 04:22:41 INFO - "stage_platform": "macosx64", 04:22:41 INFO - "builduid": "3e330e94e72a44aabac68afc380e9ed1", 04:22:41 INFO - "slavebuilddir": "test" 04:22:41 INFO - } 04:22:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg. 04:22:41 INFO - Running post-action listener: _resource_record_post_action 04:22:41 INFO - ##### 04:22:41 INFO - ##### Running download-and-extract step. 04:22:41 INFO - ##### 04:22:41 INFO - Running pre-action listener: _resource_record_pre_action 04:22:41 INFO - Running main action method: download_and_extract 04:22:41 INFO - mkdir: /builds/slave/test/build/tests 04:22: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')]} 04:22:41 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json matches https://queue.taskcluster.net 04:22:41 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:22:41 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json 04:22:41 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:22:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json'}, attempt #1 04:22:42 INFO - Downloaded 1199 bytes. 04:22:42 INFO - Reading from file /builds/slave/test/build/firefox-48.0a2.en-US.mac.test_packages.json 04:22:42 INFO - Using the following test package requirements: 04:22:42 INFO - {u'common': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:22:42 INFO - u'cppunittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.cppunittest.tests.zip'], 04:22:42 INFO - u'gtest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.gtest.tests.zip'], 04:22:42 INFO - u'jittest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'jsshell-mac.zip'], 04:22:42 INFO - u'mochitest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.mochitest.tests.zip'], 04:22:42 INFO - u'mozbase': [u'firefox-48.0a2.en-US.mac.common.tests.zip'], 04:22:42 INFO - u'reftest': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.reftest.tests.zip'], 04:22:42 INFO - u'talos': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.talos.tests.zip'], 04:22:42 INFO - u'web-platform': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.web-platform.tests.zip'], 04:22:42 INFO - u'xpcshell': [u'firefox-48.0a2.en-US.mac.common.tests.zip', 04:22:42 INFO - u'firefox-48.0a2.en-US.mac.xpcshell.tests.zip']} 04:22:42 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip'] for test suite category: common 04:22:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:42 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:22:42 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:22:42 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:22:42 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:22:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:22:43 INFO - Downloaded 18088815 bytes. 04:22:43 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:22:43 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:22:47 INFO - Return code: 0 04:22:47 INFO - Downloading packages: [u'firefox-48.0a2.en-US.mac.common.tests.zip', u'firefox-48.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 04:22:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:47 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 04:22:47 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:22:47 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:22:47 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip 04:22:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip'}, attempt #1 04:22:48 INFO - Downloaded 18088815 bytes. 04:22:48 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:22:48 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 04:22:52 INFO - Return code: 0 04:22:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:52 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 04:22:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:22:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:22:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip 04:22:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip'}, attempt #1 04:22:53 INFO - Downloaded 10840145 bytes. 04:22:53 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 04:22:53 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-48.0a2.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 04:22:53 INFO - Return code: 0 04:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:22:53 INFO - https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg matches https://queue.taskcluster.net 04:22:53 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:22:53 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:22:53 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg 04:22:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg'}, attempt #1 04:22:56 INFO - Downloaded 114083715 bytes. 04:22:56 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:22:56 INFO - mkdir: /builds/slave/test/properties 04:22:56 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:22:56 INFO - Writing to file /builds/slave/test/properties/build_url 04:22:56 INFO - Contents: 04:22:56 INFO - build_url:https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg 04:22:58 INFO - Running post-action listener: _resource_record_post_action 04:22:58 INFO - Running post-action listener: set_extra_try_arguments 04:22:58 INFO - ##### 04:22:58 INFO - ##### Running populate-webroot step. 04:22:58 INFO - ##### 04:22:58 INFO - Running pre-action listener: _resource_record_pre_action 04:22:58 INFO - Running main action method: populate_webroot 04:22:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:22:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:22:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:22:58 INFO - '--tppagecycles', 04:22:58 INFO - '7'], 04:22:58 INFO - 'tests': ['tsvgm']}}, 04:22:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:22:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:22:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:22:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:22:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:22:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['damp', 'tps']}, 04:22:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'tests': ['damp', 'tps']}, 04:22:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['dromaeo_dom']}, 04:22:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:22:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['a11yr', 04:22:58 INFO - 'ts_paint', 04:22:58 INFO - 'tpaint', 04:22:58 INFO - 'sessionrestore', 04:22:58 INFO - 'sessionrestore_no_auto_restore', 04:22:58 INFO - 'tabpaint']}, 04:22:58 INFO - 'other-e10s': {'tests': ['a11yr', 04:22:58 INFO - 'ts_paint', 04:22:58 INFO - 'tpaint', 04:22:58 INFO - 'sessionrestore', 04:22:58 INFO - 'sessionrestore_no_auto_restore', 04:22:58 INFO - 'tabpaint']}, 04:22:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:22:58 INFO - 'ts_paint', 04:22:58 INFO - 'tpaint', 04:22:58 INFO - 'sessionrestore', 04:22:58 INFO - 'sessionrestore_no_auto_restore', 04:22:58 INFO - 'tabpaint']}, 04:22:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:22:58 INFO - 'ts_paint', 04:22:58 INFO - 'tpaint', 04:22:58 INFO - 'sessionrestore', 04:22:58 INFO - 'sessionrestore_no_auto_restore', 04:22:58 INFO - 'tabpaint']}, 04:22:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['a11yr', 04:22:58 INFO - 'ts_paint', 04:22:58 INFO - 'tpaint', 04:22:58 INFO - 'sessionrestore', 04:22:58 INFO - 'sessionrestore_no_auto_restore', 04:22:58 INFO - 'tabpaint']}, 04:22:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['a11yr', 04:22:58 INFO - 'ts_paint', 04:22:58 INFO - 'tpaint', 04:22:58 INFO - 'sessionrestore', 04:22:58 INFO - 'sessionrestore_no_auto_restore', 04:22:58 INFO - 'tabpaint']}, 04:22:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['tsvgx', 04:22:58 INFO - 'tsvgr_opacity', 04:22:58 INFO - 'tart', 04:22:58 INFO - 'tscrollx', 04:22:58 INFO - 'cart']}, 04:22:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:22:58 INFO - 'tsvgr_opacity', 04:22:58 INFO - 'tart', 04:22:58 INFO - 'tscrollx', 04:22:58 INFO - 'cart']}, 04:22:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'talos_options': ['--disable-e10s'], 04:22:58 INFO - 'tests': ['tp5o']}, 04:22:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'tests': ['tp5o']}, 04:22:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'talos_options': ['--disable-e10s', 04:22:58 INFO - '--xperf_path', 04:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:58 INFO - 'tests': ['tp5n']}, 04:22:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:22:58 INFO - 'talos_options': ['--xperf_path', 04:22:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:22:58 INFO - 'tests': ['tp5n']}}, 04:22:58 INFO - 'talos.zip': {'path': '', 04:22:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:22:58 INFO - Running post-action listener: _resource_record_post_action 04:22:58 INFO - ##### 04:22:58 INFO - ##### Running create-virtualenv step. 04:22:58 INFO - ##### 04:22:58 INFO - Running pre-action listener: _resource_record_pre_action 04:22:58 INFO - Running main action method: create_virtualenv 04:22:58 INFO - Creating virtualenv /builds/slave/test/build/venv 04:22:58 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 04:22:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:22:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:22:58 INFO - Using real prefix '/tools/python27' 04:22:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:22:58 INFO - Installing distribute.............................................................................................................................................................................................done. 04:23:01 INFO - Installing pip.................done. 04:23:01 INFO - Return code: 0 04:23:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:01 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 04:23:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 04:23:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:01 INFO - 'HOME': '/Users/cltbld', 04:23:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:01 INFO - 'LOGNAME': 'cltbld', 04:23:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:01 INFO - 'MOZ_NO_REMOTE': '1', 04:23:01 INFO - 'NO_EM_RESTART': '1', 04:23:01 INFO - 'PAGER': '/bin/cat', 04:23:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:01 INFO - 'PWD': '/builds/slave/test', 04:23:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:01 INFO - 'SHELL': '/bin/bash', 04:23:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:01 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:01 INFO - 'USER': 'cltbld', 04:23:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:01 INFO - 'XPC_FLAGS': '0x0', 04:23:01 INFO - 'XPC_SERVICE_NAME': '0', 04:23:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:02 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:23:02 INFO - Cleaning up... 04:23:02 INFO - Return code: 0 04:23:02 INFO - Installing psutil>=0.7.1 into virtualenv /builds/slave/test/build/venv 04:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:02 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=0.7.1'] in /builds/slave/test/build 04:23:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=0.7.1 04:23:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:02 INFO - 'HOME': '/Users/cltbld', 04:23:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:02 INFO - 'LOGNAME': 'cltbld', 04:23:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:02 INFO - 'MOZ_NO_REMOTE': '1', 04:23:02 INFO - 'NO_EM_RESTART': '1', 04:23:02 INFO - 'PAGER': '/bin/cat', 04:23:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:02 INFO - 'PWD': '/builds/slave/test', 04:23:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:02 INFO - 'SHELL': '/bin/bash', 04:23:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:02 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:02 INFO - 'USER': 'cltbld', 04:23:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:02 INFO - 'XPC_FLAGS': '0x0', 04:23:02 INFO - 'XPC_SERVICE_NAME': '0', 04:23:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:02 INFO - Downloading/unpacking psutil>=0.7.1 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23: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 04:23:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:23:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:04 INFO - Installing collected packages: psutil 04:23:04 INFO - Running setup.py install for psutil 04:23:04 INFO - building 'psutil._psutil_osx' extension 04:23:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 04:23:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 04:23:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 04:23:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 04:23:05 INFO - building 'psutil._psutil_posix' extension 04:23:05 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 04:23:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:23:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:23:05 INFO - ^ 04:23:05 INFO - 1 warning generated. 04:23:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 04:23:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:23:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:23:06 INFO - Successfully installed psutil 04:23:06 INFO - Cleaning up... 04:23:06 INFO - Return code: 0 04:23:06 INFO - Installing mozsystemmonitor==0.0.0 into virtualenv /builds/slave/test/build/venv 04:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:06 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.0.0'] in /builds/slave/test/build 04:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.0.0 04:23:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:06 INFO - 'HOME': '/Users/cltbld', 04:23:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:06 INFO - 'LOGNAME': 'cltbld', 04:23:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:06 INFO - 'MOZ_NO_REMOTE': '1', 04:23:06 INFO - 'NO_EM_RESTART': '1', 04:23:06 INFO - 'PAGER': '/bin/cat', 04:23:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:06 INFO - 'PWD': '/builds/slave/test', 04:23:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:06 INFO - 'SHELL': '/bin/bash', 04:23:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:06 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:06 INFO - 'USER': 'cltbld', 04:23:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:06 INFO - 'XPC_FLAGS': '0x0', 04:23:06 INFO - 'XPC_SERVICE_NAME': '0', 04:23:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:06 INFO - Downloading/unpacking mozsystemmonitor==0.0.0 04:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:06 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:06 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:06 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:06 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:08 INFO - Downloading mozsystemmonitor-0.0.tar.gz 04:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:23:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=0.7.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.0.0) 04:23:08 INFO - Installing collected packages: mozsystemmonitor 04:23:08 INFO - Running setup.py install for mozsystemmonitor 04:23:09 INFO - Successfully installed mozsystemmonitor 04:23:09 INFO - Cleaning up... 04:23:09 INFO - Return code: 0 04:23:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:09 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:09 INFO - 'HOME': '/Users/cltbld', 04:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:09 INFO - 'LOGNAME': 'cltbld', 04:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:09 INFO - 'MOZ_NO_REMOTE': '1', 04:23:09 INFO - 'NO_EM_RESTART': '1', 04:23:09 INFO - 'PAGER': '/bin/cat', 04:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:09 INFO - 'PWD': '/builds/slave/test', 04:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:09 INFO - 'SHELL': '/bin/bash', 04:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:09 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:09 INFO - 'USER': 'cltbld', 04:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:09 INFO - 'XPC_FLAGS': '0x0', 04:23:09 INFO - 'XPC_SERVICE_NAME': '0', 04:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:09 INFO - Downloading/unpacking blobuploader==1.2.4 04:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:09 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:09 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:09 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:11 INFO - Downloading blobuploader-1.2.4.tar.gz 04:23:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:23:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:11 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:11 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:11 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:11 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:23:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:12 INFO - Downloading docopt-0.6.1.tar.gz 04:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:23:13 INFO - Installing collected packages: blobuploader, requests, docopt 04:23:13 INFO - Running setup.py install for blobuploader 04:23:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:23:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:23:13 INFO - Running setup.py install for requests 04:23:13 INFO - Running setup.py install for docopt 04:23:13 INFO - Successfully installed blobuploader requests docopt 04:23:13 INFO - Cleaning up... 04:23:13 INFO - Return code: 0 04:23:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:13 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:23:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:13 INFO - 'HOME': '/Users/cltbld', 04:23:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:13 INFO - 'LOGNAME': 'cltbld', 04:23:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:13 INFO - 'MOZ_NO_REMOTE': '1', 04:23:13 INFO - 'NO_EM_RESTART': '1', 04:23:13 INFO - 'PAGER': '/bin/cat', 04:23:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:13 INFO - 'PWD': '/builds/slave/test', 04:23:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:13 INFO - 'SHELL': '/bin/bash', 04:23:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:13 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:13 INFO - 'USER': 'cltbld', 04:23:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:13 INFO - 'XPC_FLAGS': '0x0', 04:23:13 INFO - 'XPC_SERVICE_NAME': '0', 04:23:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Kotpik-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-uBUhBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5nr7rZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-u5n_YW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Z_C_3j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-muIL2F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-8z96go-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-35u9ji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:14 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Q0rcJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-FYTtdW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-b65MiS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-SjuzfJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-DD_IRy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Z1ym2c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-5fHHum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-vYWmLe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:15 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-4OU8wU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:23:15 INFO - Running setup.py install for manifestparser 04:23:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:23:16 INFO - Running setup.py install for mozcrash 04:23:16 INFO - Running setup.py install for mozdebug 04:23:16 INFO - Running setup.py install for mozdevice 04:23:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:23:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:23:16 INFO - Running setup.py install for mozfile 04:23:16 INFO - Running setup.py install for mozhttpd 04:23:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:23:16 INFO - Running setup.py install for mozinfo 04:23:16 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:23:16 INFO - Running setup.py install for mozInstall 04:23:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Running setup.py install for mozleak 04:23:17 INFO - Running setup.py install for mozlog 04:23:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Running setup.py install for moznetwork 04:23:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Running setup.py install for mozprocess 04:23:17 INFO - Running setup.py install for mozprofile 04:23:17 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:23:17 INFO - Running setup.py install for mozrunner 04:23:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:23:18 INFO - Running setup.py install for mozscreenshot 04:23:18 INFO - Running setup.py install for moztest 04:23:18 INFO - Running setup.py install for mozversion 04:23:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:23:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:23:18 INFO - Cleaning up... 04:23:18 INFO - Return code: 0 04:23:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:18 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:18 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 04:23:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:18 INFO - 'HOME': '/Users/cltbld', 04:23:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:18 INFO - 'LOGNAME': 'cltbld', 04:23:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:18 INFO - 'MOZ_NO_REMOTE': '1', 04:23:18 INFO - 'NO_EM_RESTART': '1', 04:23:18 INFO - 'PAGER': '/bin/cat', 04:23:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:18 INFO - 'PWD': '/builds/slave/test', 04:23:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:18 INFO - 'SHELL': '/bin/bash', 04:23:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:18 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:18 INFO - 'USER': 'cltbld', 04:23:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:18 INFO - 'XPC_FLAGS': '0x0', 04:23:18 INFO - 'XPC_SERVICE_NAME': '0', 04:23:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:23:18 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2Ob_8R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:23:18 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:23:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:23:18 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ydbcY6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-73TyYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-XoStvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-2GUVZ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-4M4aI9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-suKE8Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-tuApp7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Rfaovn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-xW575m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.1 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Etaldq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:23:19 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:23:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:23:19 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-w4vN47-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.22 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:23:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-qwpkLI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:23:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-Hm4LkW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.11 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-ecHwBr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:23:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-bpl4z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 04:23:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:23:20 INFO - Running setup.py (path:/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/pip-jzLLPz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:23:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:23:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:23 INFO - Downloading blessings-1.6.tar.gz 04:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:23:23 INFO - Installing collected packages: blessings 04:23:23 INFO - Running setup.py install for blessings 04:23:23 INFO - Successfully installed blessings 04:23:23 INFO - Cleaning up... 04:23:23 INFO - Return code: 0 04:23:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:23:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:23 INFO - Reading from file tmpfile_stdout 04:23:23 INFO - Current package versions: 04:23:23 INFO - blessings == 1.6 04:23:23 INFO - blobuploader == 1.2.4 04:23:23 INFO - docopt == 0.6.1 04:23:23 INFO - manifestparser == 1.1 04:23:23 INFO - mozInstall == 1.12 04:23:23 INFO - mozcrash == 0.17 04:23:23 INFO - mozdebug == 0.1 04:23:23 INFO - mozdevice == 0.48 04:23:23 INFO - mozfile == 1.2 04:23:23 INFO - mozhttpd == 0.7 04:23:23 INFO - mozinfo == 0.9 04:23:23 INFO - mozleak == 0.1 04:23:23 INFO - mozlog == 3.1 04:23:23 INFO - moznetwork == 0.27 04:23:23 INFO - mozprocess == 0.22 04:23:23 INFO - mozprofile == 0.28 04:23:23 INFO - mozrunner == 6.11 04:23:23 INFO - mozscreenshot == 0.1 04:23:23 INFO - mozsystemmonitor == 0.0 04:23:23 INFO - moztest == 0.7 04:23:23 INFO - mozversion == 1.4 04:23:23 INFO - psutil == 3.1.1 04:23:23 INFO - requests == 1.2.3 04:23:23 INFO - wsgiref == 0.1.2 04:23:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:23 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 04:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 04:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:23 INFO - 'HOME': '/Users/cltbld', 04:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:23 INFO - 'LOGNAME': 'cltbld', 04:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:23 INFO - 'MOZ_NO_REMOTE': '1', 04:23:23 INFO - 'NO_EM_RESTART': '1', 04:23:23 INFO - 'PAGER': '/bin/cat', 04:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:23 INFO - 'PWD': '/builds/slave/test', 04:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:23 INFO - 'SHELL': '/bin/bash', 04:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:23 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:23 INFO - 'USER': 'cltbld', 04:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:23 INFO - 'XPC_FLAGS': '0x0', 04:23:23 INFO - 'XPC_SERVICE_NAME': '0', 04:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:23:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:23:23 INFO - Cleaning up... 04:23:23 INFO - Return code: 0 04:23:23 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:23 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 04:23:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 04:23:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:23 INFO - 'HOME': '/Users/cltbld', 04:23:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:23 INFO - 'LOGNAME': 'cltbld', 04:23:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:23 INFO - 'MOZ_NO_REMOTE': '1', 04:23:23 INFO - 'NO_EM_RESTART': '1', 04:23:23 INFO - 'PAGER': '/bin/cat', 04:23:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:23 INFO - 'PWD': '/builds/slave/test', 04:23:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:23 INFO - 'SHELL': '/bin/bash', 04:23:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:23 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:23 INFO - 'USER': 'cltbld', 04:23:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:23 INFO - 'XPC_FLAGS': '0x0', 04:23:23 INFO - 'XPC_SERVICE_NAME': '0', 04:23:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:24 INFO - Downloading/unpacking jsonschema 04:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:23:26 INFO - Downloading/unpacking functools32 (from jsonschema) 04:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:26 INFO - Downloading functools32-3.2.3-2.tar.gz 04:23:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:23:27 INFO - warning: no files found matching '*.txt' 04:23:27 INFO - no previously-included directories found matching 'build' 04:23:27 INFO - no previously-included directories found matching 'dist' 04:23:27 INFO - no previously-included directories found matching '.git*' 04:23:27 INFO - Installing collected packages: jsonschema, functools32 04:23:27 INFO - Running setup.py install for functools32 04:23:27 INFO - warning: no files found matching '*.txt' 04:23:27 INFO - no previously-included directories found matching 'build' 04:23:27 INFO - no previously-included directories found matching 'dist' 04:23:27 INFO - no previously-included directories found matching '.git*' 04:23:27 INFO - Successfully installed jsonschema functools32 04:23:27 INFO - Cleaning up... 04:23:27 INFO - Return code: 0 04:23:27 INFO - Installing flake8 into virtualenv /builds/slave/test/build/venv 04:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:23:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:23:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:23:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:23:27 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 0x101eb4a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101ec4420>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f90c8482300>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, '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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 04:23:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'flake8'] in /builds/slave/test/build 04:23:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub flake8 04:23:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:23:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:23:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:23:27 INFO - 'HOME': '/Users/cltbld', 04:23:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:23:27 INFO - 'LOGNAME': 'cltbld', 04:23:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:23:27 INFO - 'MOZ_NO_REMOTE': '1', 04:23:27 INFO - 'NO_EM_RESTART': '1', 04:23:27 INFO - 'PAGER': '/bin/cat', 04:23:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:23:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:23:27 INFO - 'PWD': '/builds/slave/test', 04:23:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:23:27 INFO - 'SHELL': '/bin/bash', 04:23:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:23:27 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:23:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:23:27 INFO - 'USER': 'cltbld', 04:23:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:23:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:23:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:23:27 INFO - 'XPC_FLAGS': '0x0', 04:23:27 INFO - 'XPC_SERVICE_NAME': '0', 04:23:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:23:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:23:27 INFO - Downloading/unpacking flake8 04:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:30 INFO - Downloading flake8-2.5.0-py2.py3-none-any.whl 04:23:30 INFO - Downloading/unpacking mccabe>=0.2.1,<0.4 (from flake8) 04:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:30 INFO - Downloading mccabe-0.3.1-py2.py3-none-any.whl 04:23:30 INFO - Downloading/unpacking pyflakes>=0.8.1,<1.1 (from flake8) 04:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:30 INFO - Downloading/unpacking pep8>=1.5.7,!=1.6.0,!=1.6.1,!=1.6.2 (from flake8) 04:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:23:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:23:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/pep8/setup.py) egg_info for package pep8 04:23:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:23:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:23:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:23:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:23:31 INFO - no previously-included directories found matching 'docs/_build' 04:23:31 INFO - Installing collected packages: flake8, mccabe, pyflakes, pep8 04:23:31 INFO - Running setup.py install for pep8 04:23:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'docs' 04:23:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'docs' 04:23:31 INFO - warning: no previously-included files matching '*.pyc' found under directory 'testsuite' 04:23:31 INFO - warning: no previously-included files matching '*.pyo' found under directory 'testsuite' 04:23:31 INFO - no previously-included directories found matching 'docs/_build' 04:23:31 INFO - Installing pep8 script to /builds/slave/test/build/venv/bin 04:23:31 INFO - Successfully installed flake8 mccabe pyflakes pep8 04:23:31 INFO - Cleaning up... 04:23:31 INFO - Return code: 0 04:23:31 INFO - Running post-action listener: _resource_record_post_action 04:23:31 INFO - Running post-action listener: _start_resource_monitoring 04:23:31 INFO - Starting resource monitoring. 04:23:31 INFO - ##### 04:23:31 INFO - ##### Running install step. 04:23:31 INFO - ##### 04:23:31 INFO - Running pre-action listener: _resource_record_pre_action 04:23:31 INFO - Running main action method: install 04:23:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:23:31 INFO - Reading from file tmpfile_stdout 04:23:31 INFO - Detecting whether we're running mozinstall >=1.0... 04:23:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:23:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:23:32 INFO - Reading from file tmpfile_stdout 04:23:32 INFO - Output received: 04:23:32 INFO - Usage: mozinstall [options] installer 04:23:32 INFO - Options: 04:23:32 INFO - -h, --help show this help message and exit 04:23:32 INFO - -d DEST, --destination=DEST 04:23:32 INFO - Directory to install application into. [default: 04:23:32 INFO - "/builds/slave/test"] 04:23:32 INFO - --app=APP Application being installed. [default: firefox] 04:23:32 INFO - mkdir: /builds/slave/test/build/application 04:23:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 04:23:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-48.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 04:24:04 INFO - Reading from file tmpfile_stdout 04:24:04 INFO - Output received: 04:24:04 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 04:24:04 INFO - Running post-action listener: _resource_record_post_action 04:24:04 INFO - ##### 04:24:04 INFO - ##### Running run-tests step. 04:24:04 INFO - ##### 04:24:04 INFO - Running pre-action listener: _resource_record_pre_action 04:24:04 INFO - Running main action method: run_tests 04:24:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:24:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:24:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:24:04 INFO - 2016-05-06 04:24:04.025 screenresolution[1637:7458] starting screenresolution argv=screenresolution get 04:24:04 INFO - 2016-05-06 04:24:04.028 screenresolution[1637:7458] Display 0: 1600x1200x32@50 04:24:04 INFO - 2016-05-06 04:24:04.034 screenresolution[1638:7460] starting screenresolution argv=screenresolution list 04:24:04 INFO - Available Modes on Display 0 04:24:04 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 04:24:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:24:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:24:04 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 04:24:04 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 04:24:04 INFO - Graphics/Displays: 04:24:04 INFO - Intel Iris: 04:24:04 INFO - Chipset Model: Intel Iris 04:24:04 INFO - Type: GPU 04:24:04 INFO - Bus: Built-In 04:24:04 INFO - VRAM (Dynamic, Max): 1536 MB 04:24:04 INFO - Vendor: Intel (0x8086) 04:24:04 INFO - Device ID: 0x0a2e 04:24:04 INFO - Revision ID: 0x0009 04:24:04 INFO - Displays: 04:24:04 INFO - Display: 04:24:04 INFO - Resolution: 1600 x 1200 @ 50 Hz 04:24:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:24:04 INFO - Display Serial Number: 1600x1200 50 04:24:04 INFO - Main Display: Yes 04:24:04 INFO - Mirror: Off 04:24:04 INFO - Online: Yes 04:24:04 INFO - Rotation: Supported 04:24:04 INFO - Return code: 0 04:24:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:24:04 INFO - Python 2.7.3 04:24:04 INFO - Return code: 0 04:24:04 INFO - grabbing minidump binary from tooltool 04:24:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 04:24:04 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 0x101d53e00>, '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 0x101bbcdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d4eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:24:04 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:24:04 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:24:04 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:24:04 INFO - Return code: 0 04:24:04 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:24:04 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:24:04 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:24:04 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:24:04 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:24:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/flake8', '/builds/slave/test/build/tests/talos/talos'] 04:24:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/flake8 /builds/slave/test/build/tests/talos/talos 04:24:05 INFO - Return code: 0 04:24:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 04:24:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0011 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 04:24:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qv5q6iZ8Bb/Render', 04:24:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:24:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:24:05 INFO - 'HOME': '/Users/cltbld', 04:24:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:24:05 INFO - 'LOGNAME': 'cltbld', 04:24:05 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:24:05 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:24:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:24:05 INFO - 'MOZ_NO_REMOTE': '1', 04:24:05 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:24:05 INFO - 'NO_EM_RESTART': '1', 04:24:05 INFO - 'PAGER': '/bin/cat', 04:24:05 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:24:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:24:05 INFO - 'PWD': '/builds/slave/test', 04:24:05 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:24:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:24:05 INFO - 'SHELL': '/bin/bash', 04:24:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.kaDnUNoRSF/Listeners', 04:24:05 INFO - 'TMPDIR': '/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/', 04:24:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:24:05 INFO - 'USER': 'cltbld', 04:24:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:24:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:24:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:24:05 INFO - 'XPC_FLAGS': '0x0', 04:24:05 INFO - 'XPC_SERVICE_NAME': '0', 04:24:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:24:05 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0011', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 04:24:05 INFO - mozversion application_buildid: 20160506013623 04:24:05 INFO - mozversion application_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:24:05 INFO - mozversion application_display_name: FirefoxDeveloperEdition 04:24:05 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:24:05 INFO - mozversion application_name: Firefox 04:24:05 INFO - mozversion application_remotingname: firefox-dev 04:24:05 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:24:05 INFO - mozversion application_vendor: Mozilla 04:24:05 INFO - mozversion application_version: 48.0a2 04:24:05 INFO - mozversion platform_buildid: 20160506013623 04:24:05 INFO - mozversion platform_changeset: b61ad3a6049cbcfe4945897e94b8d7ce5beb5c5f 04:24:05 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 04:24:05 INFO - mozversion platform_version: 48.0a2 04:24:05 INFO - using testdate: 1462533845 04:24:05 INFO - actual date: 1462533845 04:24:05 INFO - starting webserver on 'localhost:49270' 04:24:05 INFO - SUITE-START | Running 2 tests 04:24:05 INFO - TEST-START | dromaeo_css 04:24:05 INFO - operating with platform_type : mac_ 04:24:05 INFO - Initialising browser for dromaeo_css test... 04:24:05 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp2Sdpok/profile http://localhost:49270/getInfo.html) 04:24:08 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 04:24:08 INFO - PROCESS | 1656 | colorDepth:24 04:24:08 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 04:24:08 INFO - PROCESS | 1656 | __metrics 04:24:08 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:24:09 INFO - TEST-INFO | 1656: exit 0 04:24:09 INFO - Browser initialized. 04:24:09 INFO - Running cycle 1/1 for dromaeo_css test... 04:24:09 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp2Sdpok/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:24:21 INFO - PROCESS | 1659 | RSS: Main: 220532736 04:24:21 INFO - PROCESS | 1659 | 04:25:10 INFO - PROCESS | 1659 | 1462533910871 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 04:27:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 04:27:23 INFO - PROCESS | 1659 | RSS: Main: 231366656 04:27:23 INFO - PROCESS | 1659 | 04:30:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 04:30:25 INFO - PROCESS | 1659 | RSS: Main: 243056640 04:30:25 INFO - PROCESS | 1659 | 04:33:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 04:33:28 INFO - PROCESS | 1659 | RSS: Main: 245039104 04:33:28 INFO - PROCESS | 1659 | 04:36:29 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 04:36:30 INFO - PROCESS | 1659 | RSS: Main: 246525952 04:36:30 INFO - PROCESS | 1659 | 04:39:33 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 04:39:33 INFO - PROCESS | 1659 | RSS: Main: 249769984 04:39:33 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 04:42:36 INFO - PROCESS | 1659 | RSS: Main: 250802176 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | __start_tp_report 04:42:36 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 04:42:36 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 04:42:36 INFO - PROCESS | 1659 | |i|pagename|runs| 04:42:36 INFO - PROCESS | 1659 | |0;dojo.html;2527.3807917060317;3114.2992826614;3147.2603938074562;3379.9831000845;3493.4061209594365;5601.887962240753;5615.691136987464;5617.859553511159;5618.466245706656;5625.324961004675;5609.957351201427;5622.072358060915;5650.915236271459;5661.5187709044785;5707.885842283152;5134.845954621358;5161.509656582619;5196.29849970253;5211.0359583477175;5262.315898933133;5171.198464238049;5177.663451875635;5180.45605211453;5190.92213616796;5211.166213405856;5176.5858731301405;5218.556422704056;5221.556167725748;5238.423773384925;5329.600279979017;5235.895282094356;5245.554127899133;5251.2385704072885;5299.655522391032;5324.094903866352;4989.525995030466;5102.489751024905;5136.049830781318;5200;5202.661826981234;5414.91877621836;5427.837164885021;5469.398366179717;5471.616986810925;5516;4501;6152.077188421727;6185.257769067741;6197.659128747962;6204.534659900481;6118.847028824271;6183.752649894044;6240;6248.50011999039;6285.465735412496;5736.368999410061;5792.304923409218;5799.88400231993;5808.099744539603;5808.651480911159;6266.530010249249;6311.621302721851;6314.400131987454;6315.336889626615;6321.525885558602;5945.940540594625;6080.179175811209;6082.726277317532;6085.96957015221;6139.079138129317;4872.853814385574;6154.415330543591;6242.1261023456755;6255.593386429959;6276.215473065867;6211.472024877846;6212.9068063979075;6217.129601855744;6228.314885362606;6230.56386052981;6056.3338032816355;6139.676133582328;6193.411625895533;6243.906341404882;6264.5301602380005;4325.307950727868;4833.975830120827;4883.243097319921;4885.511448855086;4910.361652985089;4343.087951530214;4438.866833994985;4457.64338852891;4459.175052615276;4477.8432754854075;5522.834314970477;5637.098064309691;5657.745401456944;5753.51095156908;5803.274590676166;6224.813255602339;6245.5628106033055;6252.781152659635;6287.622742635456;6325.177726895474;5602.271704678447;5721.370649228582;5736.913946290809;5744.023516002289;5757.769689212469;5355.86610334737;5357.919631205535;5383.246345511631;5384.488473595002;5435.646682965595;6296.905546416808;6324.146240257598;6339.366063393623;6342.302346741854;6356.523260755369;6114.6636934969;6183.81448556544;6270.561060725706;6285.49716022708;6291.245050593958;6316.14732011191;6320.051992201206;6324.1146239527425;6328.493720502257;6333.398327158912;6109.84725381869;6132.478739307209;6170.5372097092;6170.722317495633;6175.228096487939;5046.47012063728;6019.819405417845;6148.4773794227995;6280.246370435394;6283.308836027942;4947.851564453073;5021.397432308147;6056.66688332146;6104.908426373519;6114.449699527064;6014.699265036818;6058.666773327509;6070.028795392716;6077.179580756541;6093.99449090895;5024.522670346311;5707.915496055736;5808.535317174531;5891;5894.970525147347;4620.468646105741;6078.270607527125;6094.725737341919;6112.17485639433;6124.8775024500155;5317.308749862493;5609.467100625434;5909.70451477433;5981.0729336952845;6000.039993601003 04:42:36 INFO - PROCESS | 1659 | |1;ext.html;510.47166183000604;535.9789600810457;540.2625416306741;568.6417556939131;582.4437662032759;32567;40915.386269205985;42055.36916946244;42075;42259.57740422595;43339.34990975138;43777.78111109448;44427.77786111073;44432.77783611086;44436.77781611087;9510.85733713993;9619;9666.661666841663;9730.756731081708;9735.123838854503;9519.428834269931;9557.952210238975;9679.467629280387;9684.61261549537;9703.854442183372;9619.230461563093;9659.855102173473;9691.563879625399;9699.757506062335;9702.757431064245;9619.855702164474;9660.37207581505;9676.12914837668;9677.274204434696;9678.999999999965;9531.571079301379;9672;9674.9516252419;9681.37071090377;9718.271129665232;39773;40092;40396;40438;40643.18713625711;2760.7101254368204;2764.8894044238205;3077.3845230953675;3227.693369129929;3252.5609042779397;22475.651460912235;25478.471291722555;26830.92676292946;26901.596476052873;28096.719032809615;2676.050002249193;2683.8658067096567;2696.9730302696917;2704.067096851583;2773.5423655096884;371225;373368;374333.99999999726;374705;376309;35882.820585897425;36122;36229;36348.63651363505;36373.90878273656;35975.10062248465;36092.45861312083;36104.91685249447;36179;36412.27175456476;12684.30236336992;15896.602584935468;15908.840911590967;15914.283857226452;15933.123678197579;13344;13357.465701371837;13392.732145357038;13416.396262168226;13418.530351437652;8221.54781487012;8290.419670622998;8318.792030199173;8397.70608028716;8443;3313.966860331414;3400.4049291373913;3426.0578340956436;3459.9135021624156;3471.5486986691567;893.5442924108752;919.8068405634891;949.2453499467939;949.9192568631744;974.332582181194;25996.35009124787;27298.727012729618;28069;28588.14235572936;28779.56830647542;835.7283882738135;928.6935311347239;989.6218869386877;1038.0087016898844;1110.1840147491587;12901.419436125208;14708.191049492167;15052.32264548098;16273.59316017109;16588.58528536772;605009;613908;621933;623502;624625;64359;64716;64769.67615161988;65315.34684653188;65495.67252163708;1342.2684636872928;1501.1218437214134;1533.9002964807476;1538.4230913407473;1541.8381069987713;31220.37559248848;31251.218719532193;31477.055688329387;31846.6815331853;32038.83980580082;56260.15609765939;56644.71677641585;57018.72359573132;57254.7137264311;57280.42719572751;563593;566664;567841;569921;571181;20872;25033.74966250314;25315;25377.61933570961;26090.73909260883;46668.299975499714;46742;47924.52075479201;47975.80060498376;48794.26808597803;30466.84766576153;33491.99524014284;38685;39380.606193937696;39703.60296396999;37037.814810925775;37505.81247093747;37793.81103094467;37980.05049873775;38283.61716382912;37960.240795184494;38415.423768644054;38460.61539384682;38691.613083868804;38969.61030389773;28668.856655717424;34178;34294;34294.828525857214;35290.647093528736;20642.380728578166;25160.748392516572;25178.748212518374;25650;25688.614670780327 04:42:36 INFO - PROCESS | 1659 | |2;jquery.html;405.472885249176;405.52958568061035;405.8457786041304;414.467409378948;415.4503701194544;14824.555263342118;20232.29186978456;20943.37169884904;20966.05652745626;20985.950702464874;15073.472428465006;20453.386398408034;20762.169513219455;21585.67621485679;22135.778642213612;5598.328200615932;5652.349979752319;5886.970565147169;5894.292684877799;5910.88178236435;4106.630403263705;5860.296764388279;5909.20225769522;5911.822645320626;5949.524038076965;5040.269160971657;5246.947530524684;5648.802291919784;5921.259842519685;5938.317093534234;5985;6003.8198854034445;6015.608985415934;6050.2739671239515;6071.78748743794;5667.943320566783;5833.445822646841;5842.24050873388;5873.1190321451695;5928.940710592925;25810.354741131436;25932;26144.607830882553;26508.60237096445;26552.468950621074;1924.7497825282624;2011.2558353409186;2107.7049213110176;2185.694002839604;2223.899924503401;9920.305578609501;10915.235933484659;11742.412879355998;14534.854651453561;14806.963512554126;1726.0334212840849;1761.4099276742306;1787.767590213277;1836.9632607347912;1850.824171703686;111029;117146.41426792812;119505;119507.402462988;120645;10161.932997035352;11869.643910682693;11957.940210298893;11985.880141198651;12040.939795300967;14812.777808332883;14955.476558320406;15256.694866102616;15287.694246115238;15314.234288285541;9054.320925930582;9196.862047069299;9199.862002069975;9213.401128926598;9298.907010929805;9159;9275.72172834816;9285.48929808867;9345.252379809735;9364.016778238323;4770.284457331359;4793.9280910786365;4796.1127191468995;4796.736179510091;4839.153148199051;1800.774903137108;1943.7181608666665;1976.2193933396227;2068.110712393685;2073.9481512961993;764.5412752348547;839.9244068033906;892.125716797542;905.1355955062899;917.9862302065475;19353.322633708034;19664.803351966584;19816.60366792656;20011.199552018046;20071;879.7272845417941;882.8719835623799;909.140861885525;933.4026223217146;942.123824842889;8329.541875196923;10308.278420510493;10472.214583906238;10596.364218146782;10608;449303;603627;613659;651068;653850;124301;128189;130076;131057;131819.7045073881;1013.640157744005;1076.0530732954949;1078.8867168947147;1083.2092572422018;1083.566573370658;15525;15666.590006899434;18484.260629574666;18679.533011674815;19315.613687726447;41036.58963410447;52297.73851130872;53865;54520;54582;74472.53164810129;77773;78099.60950195213;78661.21338786768;79038;7516.797312429985;8202.753917382488;8454.070052294366;8543.829123417621;8771.210591046582;8836.469811811312;11885.94057029744;12449.502019919102;12467.688307792378;12574.685632859251;10372.896271037192;10450.320729152581;10605.628802991858;10717.303375280582;10757.838632420364;10779.67660970172;10998;11061.949114834079;11256.887431125911;11296.774064518828;10461.520013190606;10693.358398496115;10771.246012779031;10883.782324353628;10965.122790176927;8184.058833234254;8299.253067223979;8571.142885711379;8573.957130214309;8640.56797160152;10528.842067368842;10669.199810014125;10694.89305106939;10833.29583577065;10856.294340867818 04:42:36 INFO - PROCESS | 1659 | |3;mootools.html;1401.3693837773;1606.212955651731;1783.3401641392686;1829.734688470172;1944.5721941172947;7913.446058775888;8445.155484451552;8544.145585441453;8869.46783193009;8900.554972251393;7521.834115712059;7873.763787086396;8026.317762990153;8348.833023339545;8632.956835215815;2479.3553676044266;2496.962545561818;2497.550440920643;2503;2504.672523562515;2449.069353645612;2518.798496120306;2526.7978561715113;2530.8987640494356;2530.962035569468;2256.627656436686;2361.374235827507;2530.746925307473;2535.2521006303186;2544.4911017796517;2502.987485062572;2520.269121954631;2522.672052633155;2534.8352357096824;2539.631753395747;2498.4503409249937;2527.886245118974;2528.266802627236;2534.4170840706743;2540.7586279303437;8838.98351689551;8892.73321800347;8940.15068568485;8946.284297256203;8999.010108888016;5442.809501667462;5583.748731307101;5585.553155747531;5608.383077861432;5627.846291510231;3476.165720227127;3529.100079479742;3595.0293420776507;3598.8920332389807;3630.43728222126;1020.0462567499337;1032.411525430505;1033.5968972100886;1037.6316407675242;1039.594558122333;11593.13051521131;12138.696532586668;12161.087918406154;12170.634880953585;12181.33002684852;4946.876328091755;4952.925706114411;4965.801367945314;5002.499750024968;5015.072211640858;10249.282550221413;10333;10336.5348559315;10354.637587684394;10362.844557331646;5535.6955367455175;5565.916511252334;5577.832665020056;5579.246801681721;5597.468240517144;4464.129494748558;4913.31213630092;4994.575461085776;5033.496650334937;5033.672811267256;4147.91704165915;4169.624733773914;4209.6000879916355;4232.280512312914;4247.702660813775;1369.705513314642;1387.5143699705225;1398.3497673581833;1404.4101477379525;1407.6410515318528;813.9267465928095;842.1620487614863;903.5482258870564;913.3195769152024;914.6021480655936;5714.20001199824;5769.942300577024;5808.244928159397;5898.174255604219;5994.040953447515;918.7794929217074;941.5574679900436;992.0426788149471;996.5565323431149;997.2570435025807;3640.8179591020344;3663.4504824276037;3717.8698745543775;3763.698904087721;3765.322241996467;10683.358998460117;10930;11031.172662050378;11034.889651103547;11044.171687123498;9997.400155990665;10254.077133057912;10487.003734645197;10846.891531084746;10920.890791091988;8222.095569487352;8258.8348233035;8291.378146639026;8410.579471026547;8424.620892059873;4905.828296009765;5741.684207368635;5765.8558536036935;5784.623999440023;5958.880822383441;9287.907120928974;9426.057394260793;9426.670066547638;9465.148136667733;9483.905160948578;9198.402103863227;9525.476098814632;9604.75988100303;9753.268504862022;9837.262205334771;6812.727490900506;7307.525010874276;7519.210482899217;7524.398048156025;7656.73201437947;8200.753977380688;8243.793905152419;8257.133001034805;8298.875516867372;8333;6508.60948343101;7225;7312.19565847767;7410.258974102547;7495.213002634645;7233.529820561646;7256.465677871399;7307.817304567216;7378.225286344857;7431.962840185981;6248.812725582125;6698.263191049078;6729.192496900403;6731.427828634621;6760.830979225559;4305.268104422193;4551.704139230939;4644.094401591792;4661.673682842168;4671.11248862715;4712.293156026623;4722.598579120813;4785.401824771903;4786.593139583175;4796.040791841576 04:42:36 INFO - PROCESS | 1659 | |4;prototype.html;307.7461094596958;325.47110944714836;327.8328052693126;332.48630865313106;332.615828717831;6899.137607799025;6915.48133889959;6925.27284635113;6942;6949.061876646651;5494.917576236359;6862.279460656634;6869.484788640847;6921.861562768741;6944.4097251733665;4887.095887260859;4944.233643785219;5059.595232381401;5061.215511595708;5062.164742817431;5024.874378140557;5057.898842023175;5074.086664400406;5099.617528685345;5119.641625086246;4282.97858510707;4899.044686286175;5060.7722652480725;5066.037452883958;5068.923966140511;5072.568831649296;5074.035933172722;5081.110805609041;5085.618578606601;5101.4133374662015;3552.5914519830294;4338.1540599583095;4359.455068116486;4373.431453910971;4399.208142534342;5374.892502149935;6360.650164240965;6620.768273110466;6705.865882682368;6728.39444449997;5913.615614985012;5923.111533270001;5944.4352786485215;5958.731857066399;5967.015442451991;5993.370696076936;7370.410367170613;7372.189059203483;7381.926180738177;7398.1861995181025;4323.848665296731;4390.385346051556;4715.127701375257;4717.481077081518;4738.502457241974;7565.508241964255;7822;7859.56772377519;7890;7944.404169687239;4775.068861572029;4790.209615413488;4797.18447863864;4816.614670826326;4821.662483626183;4842.273658951185;4887.193613053806;4887.584555312767;4890.731009794496;4913.21388577833;4828.61371090312;5085.8728531787;5884.499817515559;5889.146073819272;5890.67601281925;6721.327867213239;7413.073365829271;7422.294881986203;7434.73978410753;7444.367228785505;1674.3218996306273;1704.4204970310154;1706.2066139245062;1706.206613924531;1710.863130949521;1029.809485245232;1068.513826209073;1083.6207327435493;1086.842407850873;1087.8531398261134;5546.7781288748;6880.380765731008;6909;6911.308869113049;6911.9308806912295;6637.435817955431;7502.737404190827;7558;7704.114026886979;7721.150673425918;6894.345037221456;6929.272426395257;6929.653517324114;6935.757248496379;7011.6494175292055;3347.866085356607;4100.261952848516;4121.381792731115;4121.62905338515;4124;6559.934400655932;8166.305864001626;8195.42632015765;8197.303229225583;8238.629261683238;7717.151113377524;8046.074701409413;8050.154733752872;8091.0695270043525;8103.473274237156;6708.329167083448;7681.615919204018;8210.220029097107;8214;8222.671093156207;7789.06531216235;7799.454038217497;7802.063752349754;7811.687532498637;7844.254795794391;6188.597741146811;7637.5799331035105;7647.349975252166;7659.617019149132;7672.424568157522;7937.801554960941;8226.588670566329;8242.340612750846;8245.093039765501;8265.132161122996;4753.691010084333;4836.718269658473;4837.153498137742;4842.4673285939225;4863.392075990501;7767.650455729618;7778.222177782177;7784.026996625422;7797.610119493889;7844.843103137791;7196.640167991475;7468.253174682488;7485.47601667878;7486.775396738107;7496.925030749622;7035.522540266601;7378.483506154517;7450.552966822008;7501.912222727783;7551.660175292235;6449.806505804833;7334.119905611361;7359.1169059713175;7377.668004939684;7393.55638661682;6150;7438.293362130589;7468.029156209658;7507.774766757006;7514.211007844317;7030.578165310098;7241.131064272321;7315.085614298213;7332.853342933005;7345.338919497057 04:42:36 INFO - PROCESS | 1659 | |5;yui.html;678.6878036103392;686.1149117638246;687.7524091327118;710.3287393413224;747.7906186267843;15007.199136103669;15163.696726065486;15256.618584535378;15367.769483457758;17414.912925435354;15547.300371483283;15931.283092260846;16109.758353624706;16253.83746162538;16261.349546018146;733.1422235983885;900.7883147460358;917.8577320515298;926.7914719188162;926.8980412154658;901.6753968571309;908.2733812949647;919.9862002069974;921.4425272710015;922.40504874356;969.3747532841313;974.0405700385115;978.3493976505615;980.1717548671361;989.6338354808695;965.4689920543708;967.1682353176262;971.8590804333368;973.732223638498;976.077606661705;715.8675645005691;722.573681527901;724.804302838236;725.3689290317404;726.672997151284;12382.195157314836;15102.320395582115;15109.54671359861;15291.541253762405;15333.386664533406;10535.420551869644;12368;12549.811752823714;12668.303243321614;12710.555130570478;10040.69877903664;10069.54687039085;10148.1374083203;10199.694009179737;10277.126444252173;3390.16940849491;3442.1050526862946;3501.0547152268746;3513.7013353864954;3652.1782598915297;84022.31955360858;84193.57903210506;84670;84817.57591212066;84890.57554712248;7329.74345897891;9347.485888276211;9479.573419196015;9480.33637645372;9639.662611808553;20529.589408211752;20592.691109633368;20633.896830516052;20639.17443302281;20777.688334674993;7347.265273472717;7463.299913355613;7498.137714162832;7502.249775022454;7572.924270757332;3975.463312452782;4040.777757223381;4046.008727861693;4086;4096.897577560585;3430.382531144418;3643.763155394891;3648.434492653643;3702.740808143404;3715.0898029982827;552.9447055294519;660.5442244851038;664.986700265992;665.4144352969453;675.1324547955853;500.3470471035261;569.3026043097173;573.3492486028305;585.9950185432004;606.8209878085976;7593.734219302298;7921.405894557816;8091.757247282591;8210.384221183434;8266.710665126691;580.8576898659771;589.4400319696306;615.31392497366;622.3061286665426;623.9812805615838;3720.311742327678;3729.4032954727113;3758.267137908136;3760.7743535387963;3818.8090595470117;81911;98946;98999;99436;101584;71689;76612.61693691496;77895.61052194703;78167.60916195382;80233;60047.89592884566;67693;67937.30156746121;70221.59556808937;72229.55540889259;6323.36766323364;7515.248475152441;7552.546847189186;7566.281203285679;7765.922340776746;50462;50569.49430505795;51061;51117;51481.742591286806;64680.35319646743;69162.9625555607;69663;69882.98542064423;70831.93752093826;9847.556859941464;9917.30578859473;10014.899851001688;10093.394396335949;10223.335483193569;10747;10973.286736362112;11008.944955275172;11290.7741845161;11419.828702569303;9911.801763964824;9952.900470995197;10031.197504199501;10099.394036357842;10490.527926243185;10064.899351006688;10083.042110999444;10215.7446063849;10235.181185505291;10524.473776311306;9744;9828.088388847047;9996.250281228791;10151.492425378849;10202.38785672862;8773.034966153846;8956.104389561253;8995.190432861074;9045.638174472948;9186.40288381253;8648.30813534903;8687.435316704394;8755.562221888753;8856.581571303468;8861.822763544822 04:42:36 INFO - PROCESS | 1659 | __end_tp_report 04:42:36 INFO - PROCESS | 1659 | __start_cc_report 04:42:36 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,424 04:42:36 INFO - PROCESS | 1659 | __end_cc_report 04:42:36 INFO - PROCESS | 1659 | __startTimestamp1462534956308__endTimestamp 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | ------- Summary: start ------- 04:42:36 INFO - PROCESS | 1659 | Number of tests: 6 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5655.71 Median:5803.27 stddev:689.51 (11.9%) stddev-sans-first:646.48 04:42:36 INFO - PROCESS | 1659 | Values: 2527.4 3114.3 3147.3 3380.0 3493.4 5601.9 5615.7 5617.9 5618.5 5625.3 5610.0 5622.1 5650.9 5661.5 5707.9 5134.8 5161.5 5196.3 5211.0 5262.3 5171.2 5177.7 5180.5 5190.9 5211.2 5176.6 5218.6 5221.6 5238.4 5329.6 5235.9 5245.6 5251.2 5299.7 5324.1 4989.5 5102.5 5136.0 5200.0 5202.7 5414.9 5427.8 5469.4 5471.6 5516.0 4501.0 6152.1 6185.3 6197.7 6204.5 6118.8 6183.8 6240.0 6248.5 6285.5 5736.4 5792.3 5799.9 5808.1 5808.7 6266.5 6311.6 6314.4 6315.3 6321.5 5945.9 6080.2 6082.7 6086.0 6139.1 4872.9 6154.4 6242.1 6255.6 6276.2 6211.5 6212.9 6217.1 6228.3 6230.6 6056.3 6139.7 6193.4 6243.9 6264.5 4325.3 4834.0 4883.2 4885.5 4910.4 4343.1 4438.9 4457.6 4459.2 4477.8 5522.8 5637.1 5657.7 5753.5 5803.3 6224.8 6245.6 6252.8 6287.6 6325.2 5602.3 5721.4 5736.9 5744.0 5757.8 5355.9 5357.9 5383.2 5384.5 5435.6 6296.9 6324.1 6339.4 6342.3 6356.5 6114.7 6183.8 6270.6 6285.5 6291.2 6316.1 6320.1 6324.1 6328.5 6333.4 6109.8 6132.5 6170.5 6170.7 6175.2 5046.5 6019.8 6148.5 6280.2 6283.3 4947.9 5021.4 6056.7 6104.9 6114.4 6014.7 6058.7 6070.0 6077.2 6094.0 5024.5 5707.9 5808.5 5891.0 5895.0 4620.5 6078.3 6094.7 6112.2 6124.9 5317.3 5609.5 5909.7 5981.1 6000.0 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:64427.26 Median:38349.52 stddev:142047.24 (370.4%) stddev-sans-first:142364.66 04:42:36 INFO - PROCESS | 1659 | Values: 510.5 536.0 540.3 568.6 582.4 32567.0 40915.4 42055.4 42075.0 42259.6 43339.3 43777.8 44427.8 44432.8 44436.8 9510.9 9619.0 9666.7 9730.8 9735.1 9519.4 9558.0 9679.5 9684.6 9703.9 9619.2 9659.9 9691.6 9699.8 9702.8 9619.9 9660.4 9676.1 9677.3 9679.0 9531.6 9672.0 9675.0 9681.4 9718.3 39773.0 40092.0 40396.0 40438.0 40643.2 2760.7 2764.9 3077.4 3227.7 3252.6 22475.7 25478.5 26830.9 26901.6 28096.7 2676.1 2683.9 2697.0 2704.1 2773.5 371225.0 373368.0 374334.0 374705.0 376309.0 35882.8 36122.0 36229.0 36348.6 36373.9 35975.1 36092.5 36104.9 36179.0 36412.3 12684.3 15896.6 15908.8 15914.3 15933.1 13344.0 13357.5 13392.7 13416.4 13418.5 8221.5 8290.4 8318.8 8397.7 8443.0 3314.0 3400.4 3426.1 3459.9 3471.5 893.5 919.8 949.2 949.9 974.3 25996.4 27298.7 28069.0 28588.1 28779.6 835.7 928.7 989.6 1038.0 1110.2 12901.4 14708.2 15052.3 16273.6 16588.6 605009.0 613908.0 621933.0 623502.0 624625.0 64359.0 64716.0 64769.7 65315.3 65495.7 1342.3 1501.1 1533.9 1538.4 1541.8 31220.4 31251.2 31477.1 31846.7 32038.8 56260.2 56644.7 57018.7 57254.7 57280.4 563593.0 566664.0 567841.0 569921.0 571181.0 20872.0 25033.7 25315.0 25377.6 26090.7 46668.3 46742.0 47924.5 47975.8 48794.3 30466.8 33492.0 38685.0 39380.6 39703.6 37037.8 37505.8 37793.8 37980.1 38283.6 37960.2 38415.4 38460.6 38691.6 38969.6 28668.9 34178.0 34294.0 34294.8 35290.6 20642.4 25160.7 25178.7 25650.0 25688.6 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34854.42 Median:12179.84 stddev:100030.83 (821.3%) stddev-sans-first:100278.00 04:42:36 INFO - PROCESS | 1659 | Values: 405.5 405.5 405.8 414.5 415.5 14824.6 20232.3 20943.4 20966.1 20986.0 15073.5 20453.4 20762.2 21585.7 22135.8 5598.3 5652.3 5887.0 5894.3 5910.9 4106.6 5860.3 5909.2 5911.8 5949.5 5040.3 5246.9 5648.8 5921.3 5938.3 5985.0 6003.8 6015.6 6050.3 6071.8 5667.9 5833.4 5842.2 5873.1 5928.9 25810.4 25932.0 26144.6 26508.6 26552.5 1924.7 2011.3 2107.7 2185.7 2223.9 9920.3 10915.2 11742.4 14534.9 14807.0 1726.0 1761.4 1787.8 1837.0 1850.8 111029.0 117146.4 119505.0 119507.4 120645.0 10161.9 11869.6 11957.9 11985.9 12040.9 14812.8 14955.5 15256.7 15287.7 15314.2 9054.3 9196.9 9199.9 9213.4 9298.9 9159.0 9275.7 9285.5 9345.3 9364.0 4770.3 4793.9 4796.1 4796.7 4839.2 1800.8 1943.7 1976.2 2068.1 2073.9 764.5 839.9 892.1 905.1 918.0 19353.3 19664.8 19816.6 20011.2 20071.0 879.7 882.9 909.1 933.4 942.1 8329.5 10308.3 10472.2 10596.4 10608.0 449303.0 603627.0 613659.0 651068.0 653850.0 124301.0 128189.0 130076.0 131057.0 131819.7 1013.6 1076.1 1078.9 1083.2 1083.6 15525.0 15666.6 18484.3 18679.5 19315.6 41036.6 52297.7 53865.0 54520.0 54582.0 74472.5 77773.0 78099.6 78661.2 79038.0 7516.8 8202.8 8454.1 8543.8 8771.2 8836.5 11885.9 12449.5 12467.7 12574.7 10372.9 10450.3 10605.6 10717.3 10757.8 10779.7 10998.0 11061.9 11256.9 11296.8 10461.5 10693.4 10771.2 10883.8 10965.1 8184.1 8299.3 8571.1 8574.0 8640.6 10528.8 10669.2 10694.9 10833.3 10856.3 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5700.79 Median:4998.54 stddev:3151.28 (63.0%) stddev-sans-first:3143.56 04:42:36 INFO - PROCESS | 1659 | Values: 1401.4 1606.2 1783.3 1829.7 1944.6 7913.4 8445.2 8544.1 8869.5 8900.6 7521.8 7873.8 8026.3 8348.8 8633.0 2479.4 2497.0 2497.6 2503.0 2504.7 2449.1 2518.8 2526.8 2530.9 2531.0 2256.6 2361.4 2530.7 2535.3 2544.5 2503.0 2520.3 2522.7 2534.8 2539.6 2498.5 2527.9 2528.3 2534.4 2540.8 8839.0 8892.7 8940.2 8946.3 8999.0 5442.8 5583.7 5585.6 5608.4 5627.8 3476.2 3529.1 3595.0 3598.9 3630.4 1020.0 1032.4 1033.6 1037.6 1039.6 11593.1 12138.7 12161.1 12170.6 12181.3 4946.9 4952.9 4965.8 5002.5 5015.1 10249.3 10333.0 10336.5 10354.6 10362.8 5535.7 5565.9 5577.8 5579.2 5597.5 4464.1 4913.3 4994.6 5033.5 5033.7 4147.9 4169.6 4209.6 4232.3 4247.7 1369.7 1387.5 1398.3 1404.4 1407.6 813.9 842.2 903.5 913.3 914.6 5714.2 5769.9 5808.2 5898.2 5994.0 918.8 941.6 992.0 996.6 997.3 3640.8 3663.5 3717.9 3763.7 3765.3 10683.4 10930.0 11031.2 11034.9 11044.2 9997.4 10254.1 10487.0 10846.9 10920.9 8222.1 8258.8 8291.4 8410.6 8424.6 4905.8 5741.7 5765.9 5784.6 5958.9 9287.9 9426.1 9426.7 9465.1 9483.9 9198.4 9525.5 9604.8 9753.3 9837.3 6812.7 7307.5 7519.2 7524.4 7656.7 8200.8 8243.8 8257.1 8298.9 8333.0 6508.6 7225.0 7312.2 7410.3 7495.2 7233.5 7256.5 7307.8 7378.2 7432.0 6248.8 6698.3 6729.2 6731.4 6760.8 4305.3 4551.7 4644.1 4661.7 4671.1 4712.3 4722.6 4785.4 4786.6 4796.0 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:6006.76 Median:6865.88 stddev:1973.27 (28.7%) stddev-sans-first:1931.89 04:42:36 INFO - PROCESS | 1659 | Values: 307.7 325.5 327.8 332.5 332.6 6899.1 6915.5 6925.3 6942.0 6949.1 5494.9 6862.3 6869.5 6921.9 6944.4 4887.1 4944.2 5059.6 5061.2 5062.2 5024.9 5057.9 5074.1 5099.6 5119.6 4283.0 4899.0 5060.8 5066.0 5068.9 5072.6 5074.0 5081.1 5085.6 5101.4 3552.6 4338.2 4359.5 4373.4 4399.2 5374.9 6360.7 6620.8 6705.9 6728.4 5913.6 5923.1 5944.4 5958.7 5967.0 5993.4 7370.4 7372.2 7381.9 7398.2 4323.8 4390.4 4715.1 4717.5 4738.5 7565.5 7822.0 7859.6 7890.0 7944.4 4775.1 4790.2 4797.2 4816.6 4821.7 4842.3 4887.2 4887.6 4890.7 4913.2 4828.6 5085.9 5884.5 5889.1 5890.7 6721.3 7413.1 7422.3 7434.7 7444.4 1674.3 1704.4 1706.2 1706.2 1710.9 1029.8 1068.5 1083.6 1086.8 1087.9 5546.8 6880.4 6909.0 6911.3 6911.9 6637.4 7502.7 7558.0 7704.1 7721.2 6894.3 6929.3 6929.7 6935.8 7011.6 3347.9 4100.3 4121.4 4121.6 4124.0 6559.9 8166.3 8195.4 8197.3 8238.6 7717.2 8046.1 8050.2 8091.1 8103.5 6708.3 7681.6 8210.2 8214.0 8222.7 7789.1 7799.5 7802.1 7811.7 7844.3 6188.6 7637.6 7647.3 7659.6 7672.4 7937.8 8226.6 8242.3 8245.1 8265.1 4753.7 4836.7 4837.2 4842.5 4863.4 7767.7 7778.2 7784.0 7797.6 7844.8 7196.6 7468.3 7485.5 7486.8 7496.9 7035.5 7378.5 7450.6 7501.9 7551.7 6449.8 7334.1 7359.1 7377.7 7393.6 6150.0 7438.3 7468.0 7507.8 7514.2 7030.6 7241.1 7315.1 7332.9 7345.3 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:18257.19 Median:670.27 stddev:26267.32 (3918.9%) stddev-sans-first:26307.84 04:42:36 INFO - PROCESS | 1659 | Values: 678.7 686.1 687.8 710.3 747.8 15007.2 15163.7 15256.6 15367.8 17414.9 15547.3 15931.3 16109.8 16253.8 16261.3 733.1 900.8 917.9 926.8 926.9 901.7 908.3 920.0 921.4 922.4 969.4 974.0 978.3 980.2 989.6 965.5 967.2 971.9 973.7 976.1 715.9 722.6 724.8 725.4 726.7 12382.2 15102.3 15109.5 15291.5 15333.4 10535.4 12368.0 12549.8 12668.3 12710.6 10040.7 10069.5 10148.1 10199.7 10277.1 3390.2 3442.1 3501.1 3513.7 3652.2 84022.3 84193.6 84670.0 84817.6 84890.6 7329.7 9347.5 9479.6 9480.3 9639.7 20529.6 20592.7 20633.9 20639.2 20777.7 7347.3 7463.3 7498.1 7502.2 7572.9 3975.5 4040.8 4046.0 4086.0 4096.9 3430.4 3643.8 3648.4 3702.7 3715.1 552.9 660.5 665.0 665.4 675.1 500.3 569.3 573.3 586.0 606.8 7593.7 7921.4 8091.8 8210.4 8266.7 580.9 589.4 615.3 622.3 624.0 3720.3 3729.4 3758.3 3760.8 3818.8 81911.0 98946.0 98999.0 99436.0 101584.0 71689.0 76612.6 77895.6 78167.6 80233.0 60047.9 67693.0 67937.3 70221.6 72229.6 6323.4 7515.2 7552.5 7566.3 7765.9 50462.0 50569.5 51061.0 51117.0 51481.7 64680.4 69163.0 69663.0 69883.0 70831.9 9847.6 9917.3 10014.9 10093.4 10223.3 10747.0 10973.3 11008.9 11290.8 11419.8 9911.8 9952.9 10031.2 10099.4 10490.5 10064.9 10083.0 10215.7 10235.2 10524.5 9744.0 9828.1 9996.3 10151.5 10202.4 8773.0 8956.1 8995.2 9045.6 9186.4 8648.3 8687.4 8755.6 8856.6 8861.8 04:42:36 INFO - PROCESS | 1659 | -------- Summary: end -------- 04:42:36 INFO - PROCESS | 1659 | 04:42:36 INFO - TEST-INFO | 1659: exit 0 04:42:36 INFO - TEST-OK | dromaeo_css | took 1111175ms 04:42:36 INFO - TEST-START | kraken 04:42:36 INFO - operating with platform_type : mac_ 04:42:36 INFO - Initialising browser for kraken test... 04:42:36 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp1zX6qy/profile http://localhost:49270/getInfo.html) 04:42:39 INFO - PROCESS | 1671 | __metrics Screen width/height:1600/1200 04:42:39 INFO - PROCESS | 1671 | colorDepth:24 04:42:39 INFO - PROCESS | 1671 | Browser inner width/height: 1024/689 04:42:39 INFO - PROCESS | 1671 | __metrics 04:42:39 ERROR - PROCESS | 1671 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2936: TypeError: this.tabs is undefined 04:42:40 INFO - TEST-INFO | 1671: exit 0 04:42:40 INFO - Browser initialized. 04:42:40 INFO - Running cycle 1/1 for kraken test... 04:42:40 INFO - TEST-INFO | started process 1674 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/tmp1zX6qy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:42:52 INFO - PROCESS | 1674 | RSS: Main: 220995584 04:42:52 INFO - PROCESS | 1674 | 04:42:54 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 04:42:55 INFO - PROCESS | 1674 | RSS: Main: 233263104 04:42:55 INFO - PROCESS | 1674 | 04:42:58 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 04:42:59 INFO - PROCESS | 1674 | RSS: Main: 242130944 04:42:59 INFO - PROCESS | 1674 | 04:43:02 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 04:43:03 INFO - PROCESS | 1674 | RSS: Main: 245755904 04:43:03 INFO - PROCESS | 1674 | 04:43:04 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 04:43:05 INFO - PROCESS | 1674 | RSS: Main: 247648256 04:43:05 INFO - PROCESS | 1674 | 04:43:07 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:43:08 INFO - PROCESS | 1674 | RSS: Main: 247701504 04:43:08 INFO - PROCESS | 1674 | 04:43:11 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 04:43:11 INFO - PROCESS | 1674 | RSS: Main: 247873536 04:43:11 INFO - PROCESS | 1674 | 04:43:15 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 04:43:15 INFO - PROCESS | 1674 | RSS: Main: 247902208 04:43:15 INFO - PROCESS | 1674 | 04:43:18 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 04:43:19 INFO - PROCESS | 1674 | RSS: Main: 248139776 04:43:19 INFO - PROCESS | 1674 | 04:43:20 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:43:21 INFO - PROCESS | 1674 | RSS: Main: 247730176 04:43:21 INFO - PROCESS | 1674 | 04:43:23 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:43:23 INFO - PROCESS | 1674 | RSS: Main: 247832576 04:43:23 INFO - PROCESS | 1674 | 04:43:26 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:43:26 INFO - PROCESS | 1674 | RSS: Main: 248102912 04:43:26 INFO - PROCESS | 1674 | 04:43:29 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:43:30 INFO - PROCESS | 1674 | RSS: Main: 248156160 04:43:30 INFO - PROCESS | 1674 | 04:43:33 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:43:33 INFO - PROCESS | 1674 | RSS: Main: 248193024 04:43:33 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 04:43:35 INFO - PROCESS | 1674 | RSS: Main: 249901056 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | __start_tp_report 04:43:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load 04:43:35 INFO - PROCESS | 1674 | _x_x_mozilla_page_load_details 04:43:35 INFO - PROCESS | 1674 | |i|pagename|runs| 04:43:35 INFO - PROCESS | 1674 | |0;ai-astar;114;120;117;171;174;121;104;119;117;117 04:43:35 INFO - PROCESS | 1674 | |1;audio-beat-detection;181;128;153;154;122;138;137;186;147;161 04:43:35 INFO - PROCESS | 1674 | |2;audio-dft;195;199;199;206;193;210;244;195;202;197 04:43:35 INFO - PROCESS | 1674 | |3;audio-fft;61;77;75;142;56;57;55;62;130;57 04:43:35 INFO - PROCESS | 1674 | |4;audio-oscillator;79;84;156;114;85;79;104;97;116;102 04:43:35 INFO - PROCESS | 1674 | |5;imaging-gaussian-blur;89;87;89;95;76;123;134;87;90;86 04:43:35 INFO - PROCESS | 1674 | |6;imaging-darkroom;94;95;94;100;91;95;141;121;151;152 04:43:35 INFO - PROCESS | 1674 | |7;imaging-desaturate;104;97;91;100;93;95;135;90;93;89 04:43:35 INFO - PROCESS | 1674 | |8;json-parse-financial;64;66;66;70;67;68;69;90;64;63 04:43:35 INFO - PROCESS | 1674 | |9;json-stringify-tinderbox;65;68;67;71;72;77;73;70;65;69 04:43:35 INFO - PROCESS | 1674 | |10;stanford-crypto-aes;101;111;108;83;83;81;84;81;86;101 04:43:35 INFO - PROCESS | 1674 | |11;stanford-crypto-ccm;158;135;128;149;152;139;153;151;169;146 04:43:35 INFO - PROCESS | 1674 | |12;stanford-crypto-pbkdf2;178;183;171;171;174;169;169;169;169;170 04:43:35 INFO - PROCESS | 1674 | |13;stanford-crypto-sha256-iterative;68;70;70;66;71;66;78;68;60;74 04:43:35 INFO - PROCESS | 1674 | __end_tp_report 04:43:35 INFO - PROCESS | 1674 | __start_cc_report 04:43:35 INFO - PROCESS | 1674 | _x_x_mozilla_cycle_collect,1111 04:43:35 INFO - PROCESS | 1674 | __end_cc_report 04:43:35 INFO - PROCESS | 1674 | __startTimestamp1462535015828__endTimestamp 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | ------- Summary: start ------- 04:43:35 INFO - PROCESS | 1674 | Number of tests: 14 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#0] ai-astar Cycles:10 Average:127.40 Median:119.50 stddev:24.25 (20.3%) stddev-sans-first:25.23 04:43:35 INFO - PROCESS | 1674 | Values: 114.0 120.0 117.0 171.0 174.0 121.0 104.0 119.0 117.0 117.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#1] audio-beat-detection Cycles:10 Average:150.70 Median:153.50 stddev:21.05 (13.7%) stddev-sans-first:19.26 04:43:35 INFO - PROCESS | 1674 | Values: 181.0 128.0 153.0 154.0 122.0 138.0 137.0 186.0 147.0 161.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#2] audio-dft Cycles:10 Average:204.00 Median:200.50 stddev:15.00 (7.5%) stddev-sans-first:15.56 04:43:35 INFO - PROCESS | 1674 | Values: 195.0 199.0 199.0 206.0 193.0 210.0 244.0 195.0 202.0 197.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#3] audio-fft Cycles:10 Average:77.20 Median:59.00 stddev:32.05 (54.3%) stddev-sans-first:33.45 04:43:35 INFO - PROCESS | 1674 | Values: 61.0 77.0 75.0 142.0 56.0 57.0 55.0 62.0 130.0 57.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#4] audio-oscillator Cycles:10 Average:101.60 Median:79.00 stddev:23.46 (29.7%) stddev-sans-first:23.42 04:43:35 INFO - PROCESS | 1674 | Values: 79.0 84.0 156.0 114.0 85.0 79.0 104.0 97.0 116.0 102.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#5] imaging-gaussian-blur Cycles:10 Average:95.60 Median:88.00 stddev:18.16 (20.6%) stddev-sans-first:19.10 04:43:35 INFO - PROCESS | 1674 | Values: 89.0 87.0 89.0 95.0 76.0 123.0 134.0 87.0 90.0 86.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#6] imaging-darkroom Cycles:10 Average:113.40 Median:92.50 stddev:25.46 (27.5%) stddev-sans-first:26.02 04:43:35 INFO - PROCESS | 1674 | Values: 94.0 95.0 94.0 100.0 91.0 95.0 141.0 121.0 151.0 152.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#7] imaging-desaturate Cycles:10 Average:98.70 Median:92.00 stddev:13.57 (14.8%) stddev-sans-first:14.26 04:43:35 INFO - PROCESS | 1674 | Values: 104.0 97.0 91.0 100.0 93.0 95.0 135.0 90.0 93.0 89.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#8] json-parse-financial Cycles:10 Average:68.70 Median:67.50 stddev:7.82 (11.6%) stddev-sans-first:8.11 04:43:35 INFO - PROCESS | 1674 | Values: 64.0 66.0 66.0 70.0 67.0 68.0 69.0 90.0 64.0 63.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#9] json-stringify-tinderbox Cycles:10 Average:69.70 Median:70.50 stddev:3.74 (5.3%) stddev-sans-first:3.56 04:43:35 INFO - PROCESS | 1674 | Values: 65.0 68.0 67.0 71.0 72.0 77.0 73.0 70.0 65.0 69.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#10] stanford-crypto-aes Cycles:10 Average:91.90 Median:82.00 stddev:11.94 (14.6%) stddev-sans-first:12.20 04:43:35 INFO - PROCESS | 1674 | Values: 101.0 111.0 108.0 83.0 83.0 81.0 84.0 81.0 86.0 101.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#11] stanford-crypto-ccm Cycles:10 Average:148.00 Median:151.50 stddev:11.77 (7.8%) stddev-sans-first:11.91 04:43:35 INFO - PROCESS | 1674 | Values: 158.0 135.0 128.0 149.0 152.0 139.0 153.0 151.0 169.0 146.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.30 Median:171.00 stddev:4.74 (2.8%) stddev-sans-first:4.56 04:43:35 INFO - PROCESS | 1674 | Values: 178.0 183.0 171.0 171.0 174.0 169.0 169.0 169.0 169.0 170.0 04:43:35 INFO - PROCESS | 1674 | 04:43:35 INFO - PROCESS | 1674 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.10 Median:70.00 stddev:4.86 (6.9%) stddev-sans-first:5.14 04:43:35 INFO - PROCESS | 1674 | Values: 68.0 70.0 70.0 66.0 71.0 66.0 78.0 68.0 60.0 74.0 04:43:35 INFO - PROCESS | 1674 | -------- Summary: end -------- 04:43:35 INFO - PROCESS | 1674 | 04:43:36 INFO - TEST-INFO | 1674: exit 0 04:43:36 INFO - TEST-OK | kraken | took 59725ms 04:43:36 INFO - SUITE-END | took 1170s 04:43:36 INFO - Completed test suite (00:19:31) 04:43:36 INFO - javascript benchmark 04:43:37 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2527.3807917060317, 3114.2992826614, 3147.2603938074562, 3379.9831000845, 3493.4061209594365, 5601.887962240753, 5615.691136987464, 5617.859553511159, 5618.466245706656, 5625.324961004675, 5609.957351201427, 5622.072358060915, 5650.915236271459, 5661.5187709044785, 5707.885842283152, 5134.845954621358, 5161.509656582619, 5196.29849970253, 5211.0359583477175, 5262.315898933133, 5171.198464238049, 5177.663451875635, 5180.45605211453, 5190.92213616796, 5211.166213405856, 5176.5858731301405, 5218.556422704056, 5221.556167725748, 5238.423773384925, 5329.600279979017, 5235.895282094356, 5245.554127899133, 5251.2385704072885, 5299.655522391032, 5324.094903866352, 4989.525995030466, 5102.489751024905, 5136.049830781318, 5200.0, 5202.661826981234, 5414.91877621836, 5427.837164885021, 5469.398366179717, 5471.616986810925, 5516.0, 4501.0, 6152.077188421727, 6185.257769067741, 6197.659128747962, 6204.534659900481, 6118.847028824271, 6183.752649894044, 6240.0, 6248.50011999039, 6285.465735412496, 5736.368999410061, 5792.304923409218, 5799.88400231993, 5808.099744539603, 5808.651480911159, 6266.530010249249, 6311.621302721851, 6314.400131987454, 6315.336889626615, 6321.525885558602, 5945.940540594625, 6080.179175811209, 6082.726277317532, 6085.96957015221, 6139.079138129317, 4872.853814385574, 6154.415330543591, 6242.1261023456755, 6255.593386429959, 6276.215473065867, 6211.472024877846, 6212.9068063979075, 6217.129601855744, 6228.314885362606, 6230.56386052981, 6056.3338032816355, 6139.676133582328, 6193.411625895533, 6243.906341404882, 6264.5301602380005, 4325.307950727868, 4833.975830120827, 4883.243097319921, 4885.511448855086, 4910.361652985089, 4343.087951530214, 4438.866833994985, 4457.64338852891, 4459.175052615276, 4477.8432754854075, 5522.834314970477, 5637.098064309691, 5657.745401456944, 5753.51095156908, 5803.274590676166, 6224.813255602339, 6245.5628106033055, 6252.781152659635, 6287.622742635456, 6325.177726895474, 5602.271704678447, 5721.370649228582, 5736.913946290809, 5744.023516002289, 5757.769689212469, 5355.86610334737, 5357.919631205535, 5383.246345511631, 5384.488473595002, 5435.646682965595, 6296.905546416808, 6324.146240257598, 6339.366063393623, 6342.302346741854, 6356.523260755369, 6114.6636934969, 6183.81448556544, 6270.561060725706, 6285.49716022708, 6291.245050593958, 6316.14732011191, 6320.051992201206, 6324.1146239527425, 6328.493720502257, 6333.398327158912, 6109.84725381869, 6132.478739307209, 6170.5372097092, 6170.722317495633, 6175.228096487939, 5046.47012063728, 6019.819405417845, 6148.4773794227995, 6280.246370435394, 6283.308836027942, 4947.851564453073, 5021.397432308147, 6056.66688332146, 6104.908426373519, 6114.449699527064, 6014.699265036818, 6058.666773327509, 6070.028795392716, 6077.179580756541, 6093.99449090895, 5024.522670346311, 5707.915496055736, 5808.535317174531, 5891.0, 5894.970525147347, 4620.468646105741, 6078.270607527125, 6094.725737341919, 6112.17485639433, 6124.8775024500155, 5317.308749862493, 5609.467100625434, 5909.70451477433, 5981.0729336952845, 6000.039993601003], "value": 5611.264077224842, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.47166183000604, 535.9789600810457, 540.2625416306741, 568.6417556939131, 582.4437662032759, 32567.0, 40915.386269205985, 42055.36916946244, 42075.0, 42259.57740422595, 43339.34990975138, 43777.78111109448, 44427.77786111073, 44432.77783611086, 44436.77781611087, 9510.85733713993, 9619.0, 9666.661666841663, 9730.756731081708, 9735.123838854503, 9519.428834269931, 9557.952210238975, 9679.467629280387, 9684.61261549537, 9703.854442183372, 9619.230461563093, 9659.855102173473, 9691.563879625399, 9699.757506062335, 9702.757431064245, 9619.855702164474, 9660.37207581505, 9676.12914837668, 9677.274204434696, 9678.999999999965, 9531.571079301379, 9672.0, 9674.9516252419, 9681.37071090377, 9718.271129665232, 39773.0, 40092.0, 40396.0, 40438.0, 40643.18713625711, 2760.7101254368204, 2764.8894044238205, 3077.3845230953675, 3227.693369129929, 3252.5609042779397, 22475.651460912235, 25478.471291722555, 26830.92676292946, 26901.596476052873, 28096.719032809615, 2676.050002249193, 2683.8658067096567, 2696.9730302696917, 2704.067096851583, 2773.5423655096884, 371225.0, 373368.0, 374333.99999999726, 374705.0, 376309.0, 35882.820585897425, 36122.0, 36229.0, 36348.63651363505, 36373.90878273656, 35975.10062248465, 36092.45861312083, 36104.91685249447, 36179.0, 36412.27175456476, 12684.30236336992, 15896.602584935468, 15908.840911590967, 15914.283857226452, 15933.123678197579, 13344.0, 13357.465701371837, 13392.732145357038, 13416.396262168226, 13418.530351437652, 8221.54781487012, 8290.419670622998, 8318.792030199173, 8397.70608028716, 8443.0, 3313.966860331414, 3400.4049291373913, 3426.0578340956436, 3459.9135021624156, 3471.5486986691567, 893.5442924108752, 919.8068405634891, 949.2453499467939, 949.9192568631744, 974.332582181194, 25996.35009124787, 27298.727012729618, 28069.0, 28588.14235572936, 28779.56830647542, 835.7283882738135, 928.6935311347239, 989.6218869386877, 1038.0087016898844, 1110.1840147491587, 12901.419436125208, 14708.191049492167, 15052.32264548098, 16273.59316017109, 16588.58528536772, 605009.0, 613908.0, 621933.0, 623502.0, 624625.0, 64359.0, 64716.0, 64769.67615161988, 65315.34684653188, 65495.67252163708, 1342.2684636872928, 1501.1218437214134, 1533.9002964807476, 1538.4230913407473, 1541.8381069987713, 31220.37559248848, 31251.218719532193, 31477.055688329387, 31846.6815331853, 32038.83980580082, 56260.15609765939, 56644.71677641585, 57018.72359573132, 57254.7137264311, 57280.42719572751, 563593.0, 566664.0, 567841.0, 569921.0, 571181.0, 20872.0, 25033.74966250314, 25315.0, 25377.61933570961, 26090.73909260883, 46668.299975499714, 46742.0, 47924.52075479201, 47975.80060498376, 48794.26808597803, 30466.84766576153, 33491.99524014284, 38685.0, 39380.606193937696, 39703.60296396999, 37037.814810925775, 37505.81247093747, 37793.81103094467, 37980.05049873775, 38283.61716382912, 37960.240795184494, 38415.423768644054, 38460.61539384682, 38691.613083868804, 38969.61030389773, 28668.856655717424, 34178.0, 34294.0, 34294.828525857214, 35290.647093528736, 20642.380728578166, 25160.748392516572, 25178.748212518374, 25650.0, 25688.614670780327], "value": 18206.93235565532, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [405.472885249176, 405.52958568061035, 405.8457786041304, 414.467409378948, 415.4503701194544, 14824.555263342118, 20232.29186978456, 20943.37169884904, 20966.05652745626, 20985.950702464874, 15073.472428465006, 20453.386398408034, 20762.169513219455, 21585.67621485679, 22135.778642213612, 5598.328200615932, 5652.349979752319, 5886.970565147169, 5894.292684877799, 5910.88178236435, 4106.630403263705, 5860.296764388279, 5909.20225769522, 5911.822645320626, 5949.524038076965, 5040.269160971657, 5246.947530524684, 5648.802291919784, 5921.259842519685, 5938.317093534234, 5985.0, 6003.8198854034445, 6015.608985415934, 6050.2739671239515, 6071.78748743794, 5667.943320566783, 5833.445822646841, 5842.24050873388, 5873.1190321451695, 5928.940710592925, 25810.354741131436, 25932.0, 26144.607830882553, 26508.60237096445, 26552.468950621074, 1924.7497825282624, 2011.2558353409186, 2107.7049213110176, 2185.694002839604, 2223.899924503401, 9920.305578609501, 10915.235933484659, 11742.412879355998, 14534.854651453561, 14806.963512554126, 1726.0334212840849, 1761.4099276742306, 1787.767590213277, 1836.9632607347912, 1850.824171703686, 111029.0, 117146.41426792812, 119505.0, 119507.402462988, 120645.0, 10161.932997035352, 11869.643910682693, 11957.940210298893, 11985.880141198651, 12040.939795300967, 14812.777808332883, 14955.476558320406, 15256.694866102616, 15287.694246115238, 15314.234288285541, 9054.320925930582, 9196.862047069299, 9199.862002069975, 9213.401128926598, 9298.907010929805, 9159.0, 9275.72172834816, 9285.48929808867, 9345.252379809735, 9364.016778238323, 4770.284457331359, 4793.9280910786365, 4796.1127191468995, 4796.736179510091, 4839.153148199051, 1800.774903137108, 1943.7181608666665, 1976.2193933396227, 2068.110712393685, 2073.9481512961993, 764.5412752348547, 839.9244068033906, 892.125716797542, 905.1355955062899, 917.9862302065475, 19353.322633708034, 19664.803351966584, 19816.60366792656, 20011.199552018046, 20071.0, 879.7272845417941, 882.8719835623799, 909.140861885525, 933.4026223217146, 942.123824842889, 8329.541875196923, 10308.278420510493, 10472.214583906238, 10596.364218146782, 10608.0, 449303.0, 603627.0, 613659.0, 651068.0, 653850.0, 124301.0, 128189.0, 130076.0, 131057.0, 131819.7045073881, 1013.640157744005, 1076.0530732954949, 1078.8867168947147, 1083.2092572422018, 1083.566573370658, 15525.0, 15666.590006899434, 18484.260629574666, 18679.533011674815, 19315.613687726447, 41036.58963410447, 52297.73851130872, 53865.0, 54520.0, 54582.0, 74472.53164810129, 77773.0, 78099.60950195213, 78661.21338786768, 79038.0, 7516.797312429985, 8202.753917382488, 8454.070052294366, 8543.829123417621, 8771.210591046582, 8836.469811811312, 11885.94057029744, 12449.502019919102, 12467.688307792378, 12574.685632859251, 10372.896271037192, 10450.320729152581, 10605.628802991858, 10717.303375280582, 10757.838632420364, 10779.67660970172, 10998.0, 11061.949114834079, 11256.887431125911, 11296.774064518828, 10461.520013190606, 10693.358398496115, 10771.246012779031, 10883.782324353628, 10965.122790176927, 8184.058833234254, 8299.253067223979, 8571.142885711379, 8573.957130214309, 8640.56797160152, 10528.842067368842, 10669.199810014125, 10694.89305106939, 10833.29583577065, 10856.294340867818], "value": 9565.564349665556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.3693837773, 1606.212955651731, 1783.3401641392686, 1829.734688470172, 1944.5721941172947, 7913.446058775888, 8445.155484451552, 8544.145585441453, 8869.46783193009, 8900.554972251393, 7521.834115712059, 7873.763787086396, 8026.317762990153, 8348.833023339545, 8632.956835215815, 2479.3553676044266, 2496.962545561818, 2497.550440920643, 2503.0, 2504.672523562515, 2449.069353645612, 2518.798496120306, 2526.7978561715113, 2530.8987640494356, 2530.962035569468, 2256.627656436686, 2361.374235827507, 2530.746925307473, 2535.2521006303186, 2544.4911017796517, 2502.987485062572, 2520.269121954631, 2522.672052633155, 2534.8352357096824, 2539.631753395747, 2498.4503409249937, 2527.886245118974, 2528.266802627236, 2534.4170840706743, 2540.7586279303437, 8838.98351689551, 8892.73321800347, 8940.15068568485, 8946.284297256203, 8999.010108888016, 5442.809501667462, 5583.748731307101, 5585.553155747531, 5608.383077861432, 5627.846291510231, 3476.165720227127, 3529.100079479742, 3595.0293420776507, 3598.8920332389807, 3630.43728222126, 1020.0462567499337, 1032.411525430505, 1033.5968972100886, 1037.6316407675242, 1039.594558122333, 11593.13051521131, 12138.696532586668, 12161.087918406154, 12170.634880953585, 12181.33002684852, 4946.876328091755, 4952.925706114411, 4965.801367945314, 5002.499750024968, 5015.072211640858, 10249.282550221413, 10333.0, 10336.5348559315, 10354.637587684394, 10362.844557331646, 5535.6955367455175, 5565.916511252334, 5577.832665020056, 5579.246801681721, 5597.468240517144, 4464.129494748558, 4913.31213630092, 4994.575461085776, 5033.496650334937, 5033.672811267256, 4147.91704165915, 4169.624733773914, 4209.6000879916355, 4232.280512312914, 4247.702660813775, 1369.705513314642, 1387.5143699705225, 1398.3497673581833, 1404.4101477379525, 1407.6410515318528, 813.9267465928095, 842.1620487614863, 903.5482258870564, 913.3195769152024, 914.6021480655936, 5714.20001199824, 5769.942300577024, 5808.244928159397, 5898.174255604219, 5994.040953447515, 918.7794929217074, 941.5574679900436, 992.0426788149471, 996.5565323431149, 997.2570435025807, 3640.8179591020344, 3663.4504824276037, 3717.8698745543775, 3763.698904087721, 3765.322241996467, 10683.358998460117, 10930.0, 11031.172662050378, 11034.889651103547, 11044.171687123498, 9997.400155990665, 10254.077133057912, 10487.003734645197, 10846.891531084746, 10920.890791091988, 8222.095569487352, 8258.8348233035, 8291.378146639026, 8410.579471026547, 8424.620892059873, 4905.828296009765, 5741.684207368635, 5765.8558536036935, 5784.623999440023, 5958.880822383441, 9287.907120928974, 9426.057394260793, 9426.670066547638, 9465.148136667733, 9483.905160948578, 9198.402103863227, 9525.476098814632, 9604.75988100303, 9753.268504862022, 9837.262205334771, 6812.727490900506, 7307.525010874276, 7519.210482899217, 7524.398048156025, 7656.73201437947, 8200.753977380688, 8243.793905152419, 8257.133001034805, 8298.875516867372, 8333.0, 6508.60948343101, 7225.0, 7312.19565847767, 7410.258974102547, 7495.213002634645, 7233.529820561646, 7256.465677871399, 7307.817304567216, 7378.225286344857, 7431.962840185981, 6248.812725582125, 6698.263191049078, 6729.192496900403, 6731.427828634621, 6760.830979225559, 4305.268104422193, 4551.704139230939, 4644.094401591792, 4661.673682842168, 4671.11248862715, 4712.293156026623, 4722.598579120813, 4785.401824771903, 4786.593139583175, 4796.040791841576], "value": 4623.072115426887, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [307.7461094596958, 325.47110944714836, 327.8328052693126, 332.48630865313106, 332.615828717831, 6899.137607799025, 6915.48133889959, 6925.27284635113, 6942.0, 6949.061876646651, 5494.917576236359, 6862.279460656634, 6869.484788640847, 6921.861562768741, 6944.4097251733665, 4887.095887260859, 4944.233643785219, 5059.595232381401, 5061.215511595708, 5062.164742817431, 5024.874378140557, 5057.898842023175, 5074.086664400406, 5099.617528685345, 5119.641625086246, 4282.97858510707, 4899.044686286175, 5060.7722652480725, 5066.037452883958, 5068.923966140511, 5072.568831649296, 5074.035933172722, 5081.110805609041, 5085.618578606601, 5101.4133374662015, 3552.5914519830294, 4338.1540599583095, 4359.455068116486, 4373.431453910971, 4399.208142534342, 5374.892502149935, 6360.650164240965, 6620.768273110466, 6705.865882682368, 6728.39444449997, 5913.615614985012, 5923.111533270001, 5944.4352786485215, 5958.731857066399, 5967.015442451991, 5993.370696076936, 7370.410367170613, 7372.189059203483, 7381.926180738177, 7398.1861995181025, 4323.848665296731, 4390.385346051556, 4715.127701375257, 4717.481077081518, 4738.502457241974, 7565.508241964255, 7822.0, 7859.56772377519, 7890.0, 7944.404169687239, 4775.068861572029, 4790.209615413488, 4797.18447863864, 4816.614670826326, 4821.662483626183, 4842.273658951185, 4887.193613053806, 4887.584555312767, 4890.731009794496, 4913.21388577833, 4828.61371090312, 5085.8728531787, 5884.499817515559, 5889.146073819272, 5890.67601281925, 6721.327867213239, 7413.073365829271, 7422.294881986203, 7434.73978410753, 7444.367228785505, 1674.3218996306273, 1704.4204970310154, 1706.2066139245062, 1706.206613924531, 1710.863130949521, 1029.809485245232, 1068.513826209073, 1083.6207327435493, 1086.842407850873, 1087.8531398261134, 5546.7781288748, 6880.380765731008, 6909.0, 6911.308869113049, 6911.9308806912295, 6637.435817955431, 7502.737404190827, 7558.0, 7704.114026886979, 7721.150673425918, 6894.345037221456, 6929.272426395257, 6929.653517324114, 6935.757248496379, 7011.6494175292055, 3347.866085356607, 4100.261952848516, 4121.381792731115, 4121.62905338515, 4124.0, 6559.934400655932, 8166.305864001626, 8195.42632015765, 8197.303229225583, 8238.629261683238, 7717.151113377524, 8046.074701409413, 8050.154733752872, 8091.0695270043525, 8103.473274237156, 6708.329167083448, 7681.615919204018, 8210.220029097107, 8214.0, 8222.671093156207, 7789.06531216235, 7799.454038217497, 7802.063752349754, 7811.687532498637, 7844.254795794391, 6188.597741146811, 7637.5799331035105, 7647.349975252166, 7659.617019149132, 7672.424568157522, 7937.801554960941, 8226.588670566329, 8242.340612750846, 8245.093039765501, 8265.132161122996, 4753.691010084333, 4836.718269658473, 4837.153498137742, 4842.4673285939225, 4863.392075990501, 7767.650455729618, 7778.222177782177, 7784.026996625422, 7797.610119493889, 7844.843103137791, 7196.640167991475, 7468.253174682488, 7485.47601667878, 7486.775396738107, 7496.925030749622, 7035.522540266601, 7378.483506154517, 7450.552966822008, 7501.912222727783, 7551.660175292235, 6449.806505804833, 7334.119905611361, 7359.1169059713175, 7377.668004939684, 7393.55638661682, 6150.0, 7438.293362130589, 7468.029156209658, 7507.774766757006, 7514.211007844317, 7030.578165310098, 7241.131064272321, 7315.085614298213, 7332.853342933005, 7345.338919497057], "value": 5342.8602830298905, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [678.6878036103392, 686.1149117638246, 687.7524091327118, 710.3287393413224, 747.7906186267843, 15007.199136103669, 15163.696726065486, 15256.618584535378, 15367.769483457758, 17414.912925435354, 15547.300371483283, 15931.283092260846, 16109.758353624706, 16253.83746162538, 16261.349546018146, 733.1422235983885, 900.7883147460358, 917.8577320515298, 926.7914719188162, 926.8980412154658, 901.6753968571309, 908.2733812949647, 919.9862002069974, 921.4425272710015, 922.40504874356, 969.3747532841313, 974.0405700385115, 978.3493976505615, 980.1717548671361, 989.6338354808695, 965.4689920543708, 967.1682353176262, 971.8590804333368, 973.732223638498, 976.077606661705, 715.8675645005691, 722.573681527901, 724.804302838236, 725.3689290317404, 726.672997151284, 12382.195157314836, 15102.320395582115, 15109.54671359861, 15291.541253762405, 15333.386664533406, 10535.420551869644, 12368.0, 12549.811752823714, 12668.303243321614, 12710.555130570478, 10040.69877903664, 10069.54687039085, 10148.1374083203, 10199.694009179737, 10277.126444252173, 3390.16940849491, 3442.1050526862946, 3501.0547152268746, 3513.7013353864954, 3652.1782598915297, 84022.31955360858, 84193.57903210506, 84670.0, 84817.57591212066, 84890.57554712248, 7329.74345897891, 9347.485888276211, 9479.573419196015, 9480.33637645372, 9639.662611808553, 20529.589408211752, 20592.691109633368, 20633.896830516052, 20639.17443302281, 20777.688334674993, 7347.265273472717, 7463.299913355613, 7498.137714162832, 7502.249775022454, 7572.924270757332, 3975.463312452782, 4040.777757223381, 4046.008727861693, 4086.0, 4096.897577560585, 3430.382531144418, 3643.763155394891, 3648.434492653643, 3702.740808143404, 3715.0898029982827, 552.9447055294519, 660.5442244851038, 664.986700265992, 665.4144352969453, 675.1324547955853, 500.3470471035261, 569.3026043097173, 573.3492486028305, 585.9950185432004, 606.8209878085976, 7593.734219302298, 7921.405894557816, 8091.757247282591, 8210.384221183434, 8266.710665126691, 580.8576898659771, 589.4400319696306, 615.31392497366, 622.3061286665426, 623.9812805615838, 3720.311742327678, 3729.4032954727113, 3758.267137908136, 3760.7743535387963, 3818.8090595470117, 81911.0, 98946.0, 98999.0, 99436.0, 101584.0, 71689.0, 76612.61693691496, 77895.61052194703, 78167.60916195382, 80233.0, 60047.89592884566, 67693.0, 67937.30156746121, 70221.59556808937, 72229.55540889259, 6323.36766323364, 7515.248475152441, 7552.546847189186, 7566.281203285679, 7765.922340776746, 50462.0, 50569.49430505795, 51061.0, 51117.0, 51481.742591286806, 64680.35319646743, 69162.9625555607, 69663.0, 69882.98542064423, 70831.93752093826, 9847.556859941464, 9917.30578859473, 10014.899851001688, 10093.394396335949, 10223.335483193569, 10747.0, 10973.286736362112, 11008.944955275172, 11290.7741845161, 11419.828702569303, 9911.801763964824, 9952.900470995197, 10031.197504199501, 10099.394036357842, 10490.527926243185, 10064.899351006688, 10083.042110999444, 10215.7446063849, 10235.181185505291, 10524.473776311306, 9744.0, 9828.088388847047, 9996.250281228791, 10151.492425378849, 10202.38785672862, 8773.034966153846, 8956.104389561253, 8995.190432861074, 9045.638174472948, 9186.40288381253, 8648.30813534903, 8687.435316704394, 8755.562221888753, 8856.581571303468, 8861.822763544822], "value": 6779.260478111135, "unit": "score"}], "name": "dromaeo_css", "value": 7395.849794656884, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 120.0, 117.0, 171.0, 174.0, 121.0, 104.0, 119.0, 117.0, 117.0], "value": 127.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [181.0, 128.0, 153.0, 154.0, 122.0, 138.0, 137.0, 186.0, 147.0, 161.0], "value": 150.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 199.0, 199.0, 206.0, 193.0, 210.0, 244.0, 195.0, 202.0, 197.0], "value": 204.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 77.0, 75.0, 142.0, 56.0, 57.0, 55.0, 62.0, 130.0, 57.0], "value": 77.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 84.0, 156.0, 114.0, 85.0, 79.0, 104.0, 97.0, 116.0, 102.0], "value": 101.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 89.0, 95.0, 76.0, 123.0, 134.0, 87.0, 90.0, 86.0], "value": 95.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 94.0, 100.0, 91.0, 95.0, 141.0, 121.0, 151.0, 152.0], "value": 113.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 91.0, 100.0, 93.0, 95.0, 135.0, 90.0, 93.0, 89.0], "value": 98.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 66.0, 66.0, 70.0, 67.0, 68.0, 69.0, 90.0, 64.0, 63.0], "value": 68.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 68.0, 67.0, 71.0, 72.0, 77.0, 73.0, 70.0, 65.0, 69.0], "value": 69.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 111.0, 108.0, 83.0, 83.0, 81.0, 84.0, 81.0, 86.0, 101.0], "value": 91.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 135.0, 128.0, 149.0, 152.0, 139.0, 153.0, 151.0, 169.0, 146.0], "value": 148.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [178.0, 183.0, 171.0, 171.0, 174.0, 169.0, 169.0, 169.0, 169.0, 170.0], "value": 172.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 70.0, 70.0, 66.0, 71.0, 66.0, 78.0, 68.0, 60.0, 74.0], "value": 69.1, "unit": "score"}], "name": "kraken", "value": 1588.3000000000002, "alertThreshold": 2.0}]} 04:43:37 INFO - Return code: 0 04:43:37 INFO - Validating PERFHERDER_DATA against /builds/slave/test/build/tests/talos/treeherder-schemas/performance-artifact.json 04:43:37 ERROR - # TBPL SUCCESS # 04:43:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:43:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:43:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:43:37 INFO - 2016-05-06 04:43:37.115 screenresolution[1679:10228] starting screenresolution argv=screenresolution get 04:43:37 INFO - 2016-05-06 04:43:37.118 screenresolution[1679:10228] Display 0: 1600x1200x32@50 04:43:37 INFO - 2016-05-06 04:43:37.124 screenresolution[1680:10231] starting screenresolution argv=screenresolution list 04:43:37 INFO - Available Modes on Display 0 04:43:37 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 04:43:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:43:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:43:37 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 04:43:37 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 04:43:37 INFO - Graphics/Displays: 04:43:37 INFO - Intel Iris: 04:43:37 INFO - Chipset Model: Intel Iris 04:43:37 INFO - Type: GPU 04:43:37 INFO - Bus: Built-In 04:43:37 INFO - VRAM (Dynamic, Max): 1536 MB 04:43:37 INFO - Vendor: Intel (0x8086) 04:43:37 INFO - Device ID: 0x0a2e 04:43:37 INFO - Revision ID: 0x0009 04:43:37 INFO - Displays: 04:43:37 INFO - Display: 04:43:37 INFO - Resolution: 1600 x 1200 @ 50 Hz 04:43:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:43:37 INFO - Display Serial Number: 1600x1200 50 04:43:37 INFO - Main Display: Yes 04:43:37 INFO - Mirror: Off 04:43:37 INFO - Online: Yes 04:43:37 INFO - Rotation: Supported 04:43:37 INFO - Return code: 0 04:43:37 INFO - Running post-action listener: _resource_record_post_action 04:43:37 INFO - Running post-run listener: _resource_record_post_run 04:43:38 INFO - Total resource usage - Wall time: 1205s; CPU: 53.0%; Read bytes: 38045184; Write bytes: 654471680; Read time: 392; Write time: 1929 04:43:38 INFO - install - Wall time: 32s; CPU: 55.0%; Read bytes: 351046144; Write bytes: 346347520; Read time: 27333; Write time: 689 04:43:38 INFO - run-tests - Wall time: 1173s; CPU: 53.0%; Read bytes: 36226560; Write bytes: 297236992; Read time: 372; Write time: 1205 04:43:38 INFO - Running post-run listener: _upload_blobber_files 04:43:38 INFO - Blob upload gear active. 04:43:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:43:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 04:43:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:43:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:43:38 INFO - (blobuploader) - INFO - Open directory for files ... 04:43:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:43:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:43:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:40 INFO - (blobuploader) - INFO - Done attempting. 04:43:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:43:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:43:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:43:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:43:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:43:41 INFO - (blobuploader) - INFO - Done attempting. 04:43:41 INFO - (blobuploader) - INFO - Iteration through files over. 04:43:41 INFO - Return code: 0 04:43:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:43:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:43:41 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17acd0d154af05d85103c9c8f637b0f2cbcfec5ba2e343310f431a87d5a51845fd72130087c19ff9394f1409039d6da17b33b599697f2cb54c252dea57b3b823", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6179ea5aa405cc50f2cd610836a0cee413f11ce503f1597ba819c2f40cf53f7411775dc8bd437c89a454a4102a1011b2b3caa3a53c63931ade0e8effd630be75"} 04:43:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:43:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:43:41 INFO - Contents: 04:43:41 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17acd0d154af05d85103c9c8f637b0f2cbcfec5ba2e343310f431a87d5a51845fd72130087c19ff9394f1409039d6da17b33b599697f2cb54c252dea57b3b823", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6179ea5aa405cc50f2cd610836a0cee413f11ce503f1597ba819c2f40cf53f7411775dc8bd437c89a454a4102a1011b2b3caa3a53c63931ade0e8effd630be75"} 04:43:41 INFO - Running post-run listener: copy_logs_to_upload_dir 04:43:41 INFO - Copying logs to upload dir... 04:43:41 INFO - mkdir: /builds/slave/test/build/upload/logs 04:43:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.595116 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2016-05-06 04:43:41.238958) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:43:41.242991) ========= 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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17acd0d154af05d85103c9c8f637b0f2cbcfec5ba2e343310f431a87d5a51845fd72130087c19ff9394f1409039d6da17b33b599697f2cb54c252dea57b3b823", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6179ea5aa405cc50f2cd610836a0cee413f11ce503f1597ba819c2f40cf53f7411775dc8bd437c89a454a4102a1011b2b3caa3a53c63931ade0e8effd630be75"} build_url:https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010714 build_url: 'https://queue.taskcluster.net/v1/task/E0CPWYW9SimOfXSMimByrA/artifacts/public/build/firefox-48.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/17acd0d154af05d85103c9c8f637b0f2cbcfec5ba2e343310f431a87d5a51845fd72130087c19ff9394f1409039d6da17b33b599697f2cb54c252dea57b3b823", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/6179ea5aa405cc50f2cd610836a0cee413f11ce503f1597ba819c2f40cf53f7411775dc8bd437c89a454a4102a1011b2b3caa3a53c63931ade0e8effd630be75"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-05-06 04:43:41.271579) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:43:41.272158) ========= 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.qv5q6iZ8Bb/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.kaDnUNoRSF/Listeners TMPDIR=/var/folders/93/f4rdtxvx5xg5dzcxcm29xd4c00000w/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.005005 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-05-06 04:43:41.323566) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:43:41.324178) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-05-06 04:43:41.324889) ========= ========= Total master_lag: 0.22 =========