builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0133 starttime: 1479404933.97 results: success (0) buildid: 20161117071828 builduid: 01ff11ce07094d3287adcc597025b992 revision: 97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:53.971106) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:53.971558) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:53.971846) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:53.994855) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:53.995160) ========= 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.u5y4IdXHyj/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.72g48FAOQc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-11-17 09:48:54-- 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: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 166M=0s 2016-11-17 09:48:54 (166 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081789 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:54.098535) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:54.098861) ========= 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.u5y4IdXHyj/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.72g48FAOQc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.021795 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:54.147607) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:54.147981) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e --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 97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.u5y4IdXHyj/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.72g48FAOQc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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-11-17 09:48:54,226 truncating revision to first 12 chars 2016-11-17 09:48:54,226 Setting DEBUG logging. 2016-11-17 09:48:54,226 attempt 1/10 2016-11-17 09:48:54,226 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/97f55b49a6d7?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-17 09:48:54,812 unpacking tar archive at: mozilla-aurora-97f55b49a6d7/testing/mozharness/ program finished with exit code 0 elapsedTime=0.839782 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:55.011400) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:55.011877) ========= script_repo_revision: 97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:55.012431) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:55.012835) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-17 09:48:55.031998) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-17 09:48:55.032330) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --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', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--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.u5y4IdXHyj/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.72g48FAOQc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:48:55 INFO - MultiFileLogger online at 20161117 09:48:55 in /builds/slave/test 09:48:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 09:48:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:48:55 INFO - {'append_to_log': False, 09:48:55 INFO - 'base_work_dir': '/builds/slave/test', 09:48:55 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 09:48:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:48:55 INFO - 'branch': 'Mozilla-Aurora', 09:48:55 INFO - 'buildbot_json_path': 'buildprops.json', 09:48:55 INFO - 'config_files': ('talos/mac_config.py',), 09:48:55 INFO - 'default_actions': ('clobber', 09:48:55 INFO - 'read-buildbot-config', 09:48:55 INFO - 'download-and-extract', 09:48:55 INFO - 'populate-webroot', 09:48:55 INFO - 'create-virtualenv', 09:48:55 INFO - 'install', 09:48:55 INFO - 'run-tests'), 09:48:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:48:55 INFO - 'download_minidump_stackwalk': True, 09:48:55 INFO - 'download_symbols': 'ondemand', 09:48:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:48:55 INFO - 'tooltool.py': '/tools/tooltool.py', 09:48:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:48:55 INFO - '/tools/misc-python/virtualenv.py')}, 09:48:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:48:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:48:55 INFO - 'installer_path': 'installer.exe', 09:48:55 INFO - 'log_level': 'info', 09:48:55 INFO - 'log_name': 'talos', 09:48:55 INFO - 'log_to_console': True, 09:48:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:48:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:48:55 INFO - 'opt_config_files': (), 09:48:55 INFO - 'pip_index': False, 09:48:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:48:55 INFO - 'cmd': ('bash', 09:48:55 INFO - '-c', 09:48:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:48:55 INFO - 'enabled': True, 09:48:55 INFO - 'halt_on_failure': False, 09:48:55 INFO - 'name': 'check_screen_resolution'},), 09:48:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:48:55 INFO - 'cmd': ('bash', 09:48:55 INFO - '-c', 09:48:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:48:55 INFO - 'enabled': True, 09:48:55 INFO - 'halt_on_failure': False, 09:48:55 INFO - 'name': 'check_screen_resolution'},), 09:48:55 INFO - 'run_cmd_checks_enabled': True, 09:48:55 INFO - 'sps_profile': False, 09:48:55 INFO - 'sps_profile_interval': 0, 09:48:55 INFO - 'suite': 'dromaeojs', 09:48:55 INFO - 'system_bits': '32', 09:48:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:48:55 INFO - 'title': 't-yosemite-r7-0133', 09:48:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:48:55 INFO - 'use_talos_json': True, 09:48:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:48:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:48:55 INFO - 'work_dir': 'build'} 09:48:55 INFO - [mozharness: 2016-11-17 17:48:55.231927Z] Running clobber step. 09:48:55 INFO - Running pre-action listener: _resource_record_pre_action 09:48:55 INFO - Running main action method: clobber 09:48:55 INFO - rmtree: /builds/slave/test/build 09:48:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:48:57 INFO - Running post-action listener: _resource_record_post_action 09:48:57 INFO - [mozharness: 2016-11-17 17:48:57.369901Z] Finished clobber step (success) 09:48:57 INFO - [mozharness: 2016-11-17 17:48:57.370134Z] Running read-buildbot-config step. 09:48:57 INFO - Running pre-action listener: _resource_record_pre_action 09:48:57 INFO - Running main action method: read_buildbot_config 09:48:57 INFO - Using buildbot properties: 09:48:57 INFO - { 09:48:57 INFO - "project": "", 09:48:57 INFO - "product": "firefox", 09:48:57 INFO - "script_repo_revision": "production", 09:48:57 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 09:48:57 INFO - "repository": "", 09:48:57 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 09:48:57 INFO - "buildid": "20161117071828", 09:48:57 INFO - "pgo_build": "False", 09:48:57 INFO - "basedir": "/builds/slave/test", 09:48:57 INFO - "buildnumber": 22, 09:48:57 INFO - "slavename": "t-yosemite-r7-0133", 09:48:57 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 09:48:57 INFO - "platform": "yosemite_r7", 09:48:57 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 09:48:57 INFO - "branch": "mozilla-aurora", 09:48:57 INFO - "repo_path": "releases/mozilla-aurora", 09:48:57 INFO - "revision": "97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e", 09:48:57 INFO - "stage_platform": "macosx64", 09:48:57 INFO - "builduid": "01ff11ce07094d3287adcc597025b992", 09:48:57 INFO - "slavebuilddir": "test" 09:48:57 INFO - } 09:48:57 INFO - Found installer url https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 09:48:57 INFO - Running post-action listener: _resource_record_post_action 09:48:57 INFO - [mozharness: 2016-11-17 17:48:57.375932Z] Finished read-buildbot-config step (success) 09:48:57 INFO - [mozharness: 2016-11-17 17:48:57.376112Z] Running download-and-extract step. 09:48:57 INFO - Running pre-action listener: _resource_record_pre_action 09:48:57 INFO - Running main action method: download_and_extract 09:48:57 INFO - mkdir: /builds/slave/test/build/tests 09:48:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:48:57 INFO - trying https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 09:48:57 INFO - Downloading https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:48:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 09:48:58 INFO - Downloaded 1199 bytes. 09:48:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 09:48:58 INFO - Using the following test package requirements: 09:48:58 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:48:58 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 09:48:58 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 09:48:58 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'jsshell-mac.zip'], 09:48:58 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 09:48:58 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 09:48:58 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 09:48:58 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 09:48:58 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 09:48:58 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 09:48:58 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 09:48:58 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 09:48:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:48:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:48:58 INFO - Fetch https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:48:58 INFO - Http code: 200 09:48:58 INFO - via: 1.1 b3bccc0e4c2cc0a33e7e8308d88cacdd.cloudfront.net (CloudFront) 09:48:58 INFO - x-amz-cf-id: NYyuFcYcfGtonfdOIxxQ3r5vJKi_xeq4v_d3-K7n2aeCmC1GEtAtHA== 09:48:58 INFO - x-amz-version-id: 7KlDgdNBQMvjk_WvDlAi17qmAYC5hvhg 09:48:59 INFO - Expected file size: 16327839 09:48:59 INFO - Obtained file size: 16327839 09:49:05 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 09:49:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 09:49:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 09:49:05 INFO - Fetch https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 09:49:06 INFO - Http code: 200 09:49:06 INFO - via: 1.1 bfd7406fdd888e34dfca79886cb7c964.cloudfront.net (CloudFront) 09:49:06 INFO - x-amz-cf-id: MKtaVu4Buwf1FlwPQLiMqdt5n5SBZoV9WOmjwC60SOinHqmVdKCR2Q== 09:49:06 INFO - x-amz-version-id: 7KlDgdNBQMvjk_WvDlAi17qmAYC5hvhg 09:49:06 INFO - Expected file size: 16327839 09:49:06 INFO - Obtained file size: 16327839 09:49:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 09:49:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 09:49:13 INFO - Fetch https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 09:49:14 INFO - Http code: 200 09:49:14 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 09:49:14 INFO - x-amz-cf-id: SWyemRRkzPxlcxZzc8TyfQvufGwO-1c6zgSXfkxC-pPIBgwZ2eIWxQ== 09:49:14 INFO - x-amz-version-id: cDmN7Uwo7HKmqdBRHk9NxVXYPz_POGD9 09:49:14 INFO - Expected file size: 14409038 09:49:14 INFO - Obtained file size: 14409038 09:49:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:14 INFO - trying https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:49:14 INFO - Downloading https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 09:49:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 09:49:17 INFO - Downloaded 114362106 bytes. 09:49:17 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:49:17 INFO - mkdir: /builds/slave/test/properties 09:49:17 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:49:17 INFO - Writing to file /builds/slave/test/properties/build_url 09:49:17 INFO - Contents: 09:49:17 INFO - build_url:https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 09:49:18 INFO - Running post-action listener: _resource_record_post_action 09:49:18 INFO - Running post-action listener: set_extra_try_arguments 09:49:18 INFO - [mozharness: 2016-11-17 17:49:18.395327Z] Finished download-and-extract step (success) 09:49:18 INFO - [mozharness: 2016-11-17 17:49:18.395485Z] Running populate-webroot step. 09:49:18 INFO - Running pre-action listener: _resource_record_pre_action 09:49:18 INFO - Running main action method: populate_webroot 09:49:18 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:49:18 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:49:18 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:49:18 INFO - '--tppagecycles', 09:49:18 INFO - '7'], 09:49:18 INFO - 'tests': ['tsvgm']}}, 09:49:18 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:49:18 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:49:18 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:49:18 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:49:18 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:18 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:49:18 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['damp', 'tps']}, 09:49:18 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'tests': ['damp', 'tps']}, 09:49:18 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['dromaeo_dom']}, 09:49:18 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:49:18 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['basic_compositor_video']}, 09:49:18 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:49:18 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['a11yr', 09:49:18 INFO - 'ts_paint', 09:49:18 INFO - 'tpaint', 09:49:18 INFO - 'sessionrestore', 09:49:18 INFO - 'sessionrestore_no_auto_restore', 09:49:18 INFO - 'tabpaint']}, 09:49:18 INFO - 'other-e10s': {'tests': ['a11yr', 09:49:18 INFO - 'ts_paint', 09:49:18 INFO - 'tpaint', 09:49:18 INFO - 'sessionrestore', 09:49:18 INFO - 'sessionrestore_no_auto_restore', 09:49:18 INFO - 'tabpaint']}, 09:49:18 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:49:18 INFO - 'ts_paint', 09:49:18 INFO - 'tpaint', 09:49:18 INFO - 'sessionrestore', 09:49:18 INFO - 'sessionrestore_no_auto_restore', 09:49:18 INFO - 'tabpaint']}, 09:49:18 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:49:18 INFO - 'ts_paint', 09:49:18 INFO - 'tpaint', 09:49:18 INFO - 'sessionrestore', 09:49:18 INFO - 'sessionrestore_no_auto_restore', 09:49:18 INFO - 'tabpaint']}, 09:49:18 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['a11yr', 09:49:18 INFO - 'ts_paint', 09:49:18 INFO - 'tpaint', 09:49:18 INFO - 'sessionrestore', 09:49:18 INFO - 'sessionrestore_no_auto_restore', 09:49:18 INFO - 'tabpaint']}, 09:49:18 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['a11yr', 09:49:18 INFO - 'ts_paint', 09:49:18 INFO - 'tpaint', 09:49:18 INFO - 'sessionrestore', 09:49:18 INFO - 'sessionrestore_no_auto_restore', 09:49:18 INFO - 'tabpaint']}, 09:49:18 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['tsvgx', 09:49:18 INFO - 'tsvgr_opacity', 09:49:18 INFO - 'tart', 09:49:18 INFO - 'tscrollx', 09:49:18 INFO - 'cart']}, 09:49:18 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:49:18 INFO - 'tsvgr_opacity', 09:49:18 INFO - 'tart', 09:49:18 INFO - 'tscrollx', 09:49:18 INFO - 'cart']}, 09:49:18 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'talos_options': ['--disable-e10s'], 09:49:18 INFO - 'tests': ['tp5o']}, 09:49:18 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'tests': ['tp5o']}, 09:49:18 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'talos_options': ['--disable-e10s', 09:49:18 INFO - '--xperf_path', 09:49:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:18 INFO - 'tests': ['tp5n']}, 09:49:18 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:49:18 INFO - 'talos_options': ['--xperf_path', 09:49:18 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:49:18 INFO - 'tests': ['tp5n']}}, 09:49:18 INFO - 'talos.zip': {'path': '', 09:49:18 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:49:18 INFO - Running post-action listener: _resource_record_post_action 09:49:18 INFO - [mozharness: 2016-11-17 17:49:18.414121Z] Finished populate-webroot step (success) 09:49:18 INFO - [mozharness: 2016-11-17 17:49:18.414247Z] Running create-virtualenv step. 09:49:18 INFO - Running pre-action listener: _resource_record_pre_action 09:49:18 INFO - Running main action method: create_virtualenv 09:49:18 INFO - Creating virtualenv /builds/slave/test/build/venv 09:49:18 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:49:18 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:49:18 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:49:18 INFO - Using real prefix '/tools/python27' 09:49:18 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:49:19 INFO - Installing distribute.............................................................................................................................................................................................done. 09:49:22 INFO - Installing pip.................done. 09:49:22 INFO - Return code: 0 09:49:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:49:22 INFO - Reading from file tmpfile_stdout 09:49:22 INFO - Output received: 09:49:22 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:49:22 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 09:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 09:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:22 INFO - 'HOME': '/Users/cltbld', 09:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:22 INFO - 'LOGNAME': 'cltbld', 09:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:22 INFO - 'MOZ_NO_REMOTE': '1', 09:49:22 INFO - 'NO_EM_RESTART': '1', 09:49:22 INFO - 'PAGER': '/bin/cat', 09:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:22 INFO - 'PWD': '/builds/slave/test', 09:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:22 INFO - 'SHELL': '/bin/bash', 09:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:22 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:22 INFO - 'USER': 'cltbld', 09:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:22 INFO - 'XPC_FLAGS': '0x0', 09:49:22 INFO - 'XPC_SERVICE_NAME': '0', 09:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:22 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:49:22 INFO - Cleaning up... 09:49:22 INFO - Return code: 0 09:49:22 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 09:49:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 09:49:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:22 INFO - 'HOME': '/Users/cltbld', 09:49:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:22 INFO - 'LOGNAME': 'cltbld', 09:49:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:22 INFO - 'MOZ_NO_REMOTE': '1', 09:49:22 INFO - 'NO_EM_RESTART': '1', 09:49:22 INFO - 'PAGER': '/bin/cat', 09:49:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:22 INFO - 'PWD': '/builds/slave/test', 09:49:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:22 INFO - 'SHELL': '/bin/bash', 09:49:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:22 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:22 INFO - 'USER': 'cltbld', 09:49:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:22 INFO - 'XPC_FLAGS': '0x0', 09:49:22 INFO - 'XPC_SERVICE_NAME': '0', 09:49:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:22 INFO - Downloading/unpacking psutil>=3.1.1 09:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:49:26 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:26 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:26 INFO - Installing collected packages: psutil 09:49:26 INFO - Running setup.py install for psutil 09:49:26 INFO - building 'psutil._psutil_osx' extension 09:49:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:49:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:49:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:49:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:49:26 INFO - building 'psutil._psutil_posix' extension 09:49:26 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:49:26 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:49:26 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:49:26 INFO - ^ 09:49:26 INFO - 1 warning generated. 09:49:26 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:49:27 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:49:27 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:49:27 INFO - Successfully installed psutil 09:49:27 INFO - Cleaning up... 09:49:27 INFO - Return code: 0 09:49:27 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:49: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 mozsystemmonitor==0.3 09:49:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:27 INFO - 'HOME': '/Users/cltbld', 09:49:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:27 INFO - 'LOGNAME': 'cltbld', 09:49:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:27 INFO - 'MOZ_NO_REMOTE': '1', 09:49:27 INFO - 'NO_EM_RESTART': '1', 09:49:27 INFO - 'PAGER': '/bin/cat', 09:49:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:27 INFO - 'PWD': '/builds/slave/test', 09:49:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:27 INFO - 'SHELL': '/bin/bash', 09:49:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:27 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:27 INFO - 'USER': 'cltbld', 09:49:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:27 INFO - 'XPC_FLAGS': '0x0', 09:49:27 INFO - 'XPC_SERVICE_NAME': '0', 09:49:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:27 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:49: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 09:49: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 09:49: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 09:49: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 09:49: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 09:49: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 09:49:29 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:49:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:49:30 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:49:30 INFO - Installing collected packages: mozsystemmonitor 09:49:30 INFO - Running setup.py install for mozsystemmonitor 09:49:30 INFO - Successfully installed mozsystemmonitor 09:49:30 INFO - Cleaning up... 09:49:30 INFO - Return code: 0 09:49:30 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:30 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==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:30 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==2.5.1'] in /builds/slave/test/build 09:49:30 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==2.5.1 09:49:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:30 INFO - 'HOME': '/Users/cltbld', 09:49:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:30 INFO - 'LOGNAME': 'cltbld', 09:49:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:30 INFO - 'MOZ_NO_REMOTE': '1', 09:49:30 INFO - 'NO_EM_RESTART': '1', 09:49:30 INFO - 'PAGER': '/bin/cat', 09:49:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:30 INFO - 'PWD': '/builds/slave/test', 09:49:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:30 INFO - 'SHELL': '/bin/bash', 09:49:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:30 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:30 INFO - 'USER': 'cltbld', 09:49:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:30 INFO - 'XPC_FLAGS': '0x0', 09:49:30 INFO - 'XPC_SERVICE_NAME': '0', 09:49:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:30 INFO - Downloading/unpacking jsonschema==2.5.1 09:49: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 09:49: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 09:49: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 09:49: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 09:49: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 09:49: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 09:49:32 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:49:33 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:33 INFO - Downloading functools32-3.2.3-2.tar.gz 09:49:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:49:33 INFO - warning: no files found matching '*.txt' 09:49:33 INFO - no previously-included directories found matching 'build' 09:49:33 INFO - no previously-included directories found matching 'dist' 09:49:33 INFO - no previously-included directories found matching '.git*' 09:49:33 INFO - Installing collected packages: jsonschema, functools32 09:49:33 INFO - Running setup.py install for functools32 09:49:33 INFO - warning: no files found matching '*.txt' 09:49:33 INFO - no previously-included directories found matching 'build' 09:49:33 INFO - no previously-included directories found matching 'dist' 09:49:33 INFO - no previously-included directories found matching '.git*' 09:49:33 INFO - Successfully installed jsonschema functools32 09:49:33 INFO - Cleaning up... 09:49:33 INFO - Return code: 0 09:49:33 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:33 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', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:33 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', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:49:33 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 functools32==3.2.3-2 09:49:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:33 INFO - 'HOME': '/Users/cltbld', 09:49:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:33 INFO - 'LOGNAME': 'cltbld', 09:49:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:33 INFO - 'MOZ_NO_REMOTE': '1', 09:49:33 INFO - 'NO_EM_RESTART': '1', 09:49:33 INFO - 'PAGER': '/bin/cat', 09:49:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:33 INFO - 'PWD': '/builds/slave/test', 09:49:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:33 INFO - 'SHELL': '/bin/bash', 09:49:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:33 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:33 INFO - 'USER': 'cltbld', 09:49:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:33 INFO - 'XPC_FLAGS': '0x0', 09:49:33 INFO - 'XPC_SERVICE_NAME': '0', 09:49:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:34 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:49:34 INFO - Cleaning up... 09:49:34 INFO - Return code: 0 09:49:34 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:34 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:49:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:49:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:34 INFO - 'HOME': '/Users/cltbld', 09:49:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:34 INFO - 'LOGNAME': 'cltbld', 09:49:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:34 INFO - 'MOZ_NO_REMOTE': '1', 09:49:34 INFO - 'NO_EM_RESTART': '1', 09:49:34 INFO - 'PAGER': '/bin/cat', 09:49:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:34 INFO - 'PWD': '/builds/slave/test', 09:49:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:34 INFO - 'SHELL': '/bin/bash', 09:49:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:34 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:34 INFO - 'USER': 'cltbld', 09:49:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:34 INFO - 'XPC_FLAGS': '0x0', 09:49:34 INFO - 'XPC_SERVICE_NAME': '0', 09:49:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:34 INFO - Downloading/unpacking blobuploader==1.2.4 09:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:34 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:34 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:34 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:34 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:36 INFO - Downloading blobuploader-1.2.4.tar.gz 09:49:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:49:36 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:49:37 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:38 INFO - Downloading docopt-0.6.1.tar.gz 09:49:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:49:38 INFO - Installing collected packages: blobuploader, requests, docopt 09:49:38 INFO - Running setup.py install for blobuploader 09:49:38 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:49:38 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:49:38 INFO - Running setup.py install for requests 09:49:38 INFO - Running setup.py install for docopt 09:49:38 INFO - Successfully installed blobuploader requests docopt 09:49:38 INFO - Cleaning up... 09:49:39 INFO - Return code: 0 09:49:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:39 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 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:49:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:49:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:39 INFO - 'HOME': '/Users/cltbld', 09:49:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:39 INFO - 'LOGNAME': 'cltbld', 09:49:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:39 INFO - 'MOZ_NO_REMOTE': '1', 09:49:39 INFO - 'NO_EM_RESTART': '1', 09:49:39 INFO - 'PAGER': '/bin/cat', 09:49:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:39 INFO - 'PWD': '/builds/slave/test', 09:49:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:39 INFO - 'SHELL': '/bin/bash', 09:49:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:39 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:39 INFO - 'USER': 'cltbld', 09:49:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:39 INFO - 'XPC_FLAGS': '0x0', 09:49:39 INFO - 'XPC_SERVICE_NAME': '0', 09:49:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-nyfzhd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-8JFc0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-DEjxJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-C6aETT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-KPiLLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-7Fz0iN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:39 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-f65E9D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rGLIPI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ZzEQey-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-REA_9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-uoacog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-bUXLX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-yFpWjT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-2fNOHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-pqpFst-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:40 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-8gYHMZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:41 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-9h5Cdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:41 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:49:41 INFO - Running setup.py install for manifestparser 09:49:41 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:49:41 INFO - Running setup.py install for mozcrash 09:49:41 INFO - Running setup.py install for mozdebug 09:49:41 INFO - Running setup.py install for mozdevice 09:49:41 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:49:41 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:49:41 INFO - Running setup.py install for mozfile 09:49:41 INFO - Running setup.py install for mozhttpd 09:49:42 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Running setup.py install for mozinfo 09:49:42 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Running setup.py install for mozInstall 09:49:42 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Running setup.py install for mozleak 09:49:42 INFO - Running setup.py install for mozlog 09:49:42 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Running setup.py install for moznetwork 09:49:42 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:49:42 INFO - Running setup.py install for mozprocess 09:49:42 INFO - Running setup.py install for mozprofile 09:49:43 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:49:43 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:49:43 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:49:43 INFO - Running setup.py install for mozrunner 09:49:43 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:49:43 INFO - Running setup.py install for mozscreenshot 09:49:43 INFO - Running setup.py install for moztest 09:49:43 INFO - Running setup.py install for mozversion 09:49:43 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:49:43 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:49:43 INFO - Cleaning up... 09:49:43 INFO - Return code: 0 09:49:43 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:43 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 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:49:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:49:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:43 INFO - 'HOME': '/Users/cltbld', 09:49:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:43 INFO - 'LOGNAME': 'cltbld', 09:49:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:43 INFO - 'MOZ_NO_REMOTE': '1', 09:49:43 INFO - 'NO_EM_RESTART': '1', 09:49:43 INFO - 'PAGER': '/bin/cat', 09:49:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:43 INFO - 'PWD': '/builds/slave/test', 09:49:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:43 INFO - 'SHELL': '/bin/bash', 09:49:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:43 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:43 INFO - 'USER': 'cltbld', 09:49:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:43 INFO - 'XPC_FLAGS': '0x0', 09:49:43 INFO - 'XPC_SERVICE_NAME': '0', 09:49:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-n9kqkt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-rSgNHp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-bZWjGC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-b_AFVn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-FJUfVd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-qwfoNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-CgjdGX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-9FZih4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-8z_6Zh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:49:44 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:49:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:49:44 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-xz3N9T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-TjlTfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-ibMjin-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Sfc90k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-SAozTi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-1bbbnp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-nrDOVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:49:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:49:45 INFO - Running setup.py (path:/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/pip-Q119IC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:49:45 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:49:45 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:49:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:49:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:49:48 INFO - Downloading blessings-1.6.tar.gz 09:49:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:49:48 INFO - Installing collected packages: blessings 09:49:48 INFO - Running setup.py install for blessings 09:49:48 INFO - Successfully installed blessings 09:49:48 INFO - Cleaning up... 09:49:48 INFO - Return code: 0 09:49:48 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:49:48 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:48 INFO - Reading from file tmpfile_stdout 09:49:48 INFO - Current package versions: 09:49:48 INFO - blessings == 1.6 09:49:48 INFO - blobuploader == 1.2.4 09:49:48 INFO - docopt == 0.6.1 09:49:48 INFO - functools32 == 3.2.3-2 09:49:48 INFO - jsonschema == 2.5.1 09:49:48 INFO - manifestparser == 1.1 09:49:48 INFO - mozInstall == 1.12 09:49:48 INFO - mozcrash == 1.0 09:49:48 INFO - mozdebug == 0.1 09:49:48 INFO - mozdevice == 0.48 09:49:48 INFO - mozfile == 1.2 09:49:48 INFO - mozhttpd == 0.7 09:49:48 INFO - mozinfo == 0.9 09:49:48 INFO - mozleak == 0.1 09:49:48 INFO - mozlog == 3.4 09:49:48 INFO - moznetwork == 0.27 09:49:48 INFO - mozprocess == 0.23 09:49:48 INFO - mozprofile == 0.28 09:49:48 INFO - mozrunner == 6.13 09:49:48 INFO - mozscreenshot == 0.1 09:49:48 INFO - mozsystemmonitor == 0.3 09:49:48 INFO - moztest == 0.7 09:49:48 INFO - mozversion == 1.4 09:49:48 INFO - psutil == 3.1.1 09:49:48 INFO - requests == 1.2.3 09:49:48 INFO - wsgiref == 0.1.2 09:49:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:49:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:48 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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:48 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 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:49:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:49:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:48 INFO - 'HOME': '/Users/cltbld', 09:49:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:48 INFO - 'LOGNAME': 'cltbld', 09:49:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:48 INFO - 'MOZ_NO_REMOTE': '1', 09:49:48 INFO - 'NO_EM_RESTART': '1', 09:49:48 INFO - 'PAGER': '/bin/cat', 09:49:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:48 INFO - 'PWD': '/builds/slave/test', 09:49:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:48 INFO - 'SHELL': '/bin/bash', 09:49:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:48 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:48 INFO - 'USER': 'cltbld', 09:49:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:48 INFO - 'XPC_FLAGS': '0x0', 09:49:48 INFO - 'XPC_SERVICE_NAME': '0', 09:49:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:49:49 INFO - Cleaning up... 09:49:49 INFO - Return code: 0 09:49:49 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:49:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:49:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:49:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:49:49 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 0x1020eb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102061570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd282c64940>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, '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.u5y4IdXHyj/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.72g48FAOQc/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:49:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:49:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:49:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:49:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:49:49 INFO - 'HOME': '/Users/cltbld', 09:49:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:49:49 INFO - 'LOGNAME': 'cltbld', 09:49:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:49:49 INFO - 'MOZ_NO_REMOTE': '1', 09:49:49 INFO - 'NO_EM_RESTART': '1', 09:49:49 INFO - 'PAGER': '/bin/cat', 09:49:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:49:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:49:49 INFO - 'PWD': '/builds/slave/test', 09:49:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:49:49 INFO - 'SHELL': '/bin/bash', 09:49:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:49:49 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:49:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:49:49 INFO - 'USER': 'cltbld', 09:49:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:49:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:49:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:49:49 INFO - 'XPC_FLAGS': '0x0', 09:49:49 INFO - 'XPC_SERVICE_NAME': '0', 09:49:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:49:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:49:49 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:49:49 INFO - Cleaning up... 09:49:49 INFO - Return code: 0 09:49:49 INFO - Running post-action listener: _resource_record_post_action 09:49:49 INFO - Running post-action listener: _start_resource_monitoring 09:49:49 INFO - Starting resource monitoring. 09:49:49 INFO - [mozharness: 2016-11-17 17:49:49.525660Z] Finished create-virtualenv step (success) 09:49:49 INFO - [mozharness: 2016-11-17 17:49:49.526771Z] Running install step. 09:49:49 INFO - Running pre-action listener: _resource_record_pre_action 09:49:49 INFO - Running main action method: install 09:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:49:49 INFO - Reading from file tmpfile_stdout 09:49:49 INFO - Detecting whether we're running mozinstall >=1.0... 09:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:49:49 INFO - Reading from file tmpfile_stdout 09:49:49 INFO - Output received: 09:49:49 INFO - Usage: mozinstall [options] installer 09:49:49 INFO - Options: 09:49:49 INFO - -h, --help show this help message and exit 09:49:49 INFO - -d DEST, --destination=DEST 09:49:49 INFO - Directory to install application into. [default: 09:49:49 INFO - "/builds/slave/test"] 09:49:49 INFO - --app=APP Application being installed. [default: firefox] 09:49:49 INFO - mkdir: /builds/slave/test/build/application 09:49:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:49:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 09:50:22 INFO - Reading from file tmpfile_stdout 09:50:22 INFO - Output received: 09:50:22 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 09:50:22 INFO - Running post-action listener: _resource_record_post_action 09:50:22 INFO - [mozharness: 2016-11-17 17:50:22.389105Z] Finished install step (success) 09:50:22 INFO - [mozharness: 2016-11-17 17:50:22.389266Z] Running run-tests step. 09:50:22 INFO - Running pre-action listener: _resource_record_pre_action 09:50:22 INFO - Running main action method: run_tests 09:50:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:50:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:50:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:50:22 INFO - 2016-11-17 09:50:22.404 screenresolution[2164:22397] starting screenresolution argv=screenresolution get 09:50:22 INFO - 2016-11-17 09:50:22.406 screenresolution[2164:22397] Display 0: 1600x1200x32@60 09:50:22 INFO - 2016-11-17 09:50:22.412 screenresolution[2165:22399] starting screenresolution argv=screenresolution list 09:50:22 INFO - Available Modes on Display 0 09:50:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:50:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:50:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:50:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:50:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:50:22 INFO - Intel Iris: 09:50:22 INFO - Chipset Model: Intel Iris 09:50:22 INFO - Type: GPU 09:50:22 INFO - Bus: Built-In 09:50:22 INFO - VRAM (Dynamic, Max): 1536 MB 09:50:22 INFO - Vendor: Intel (0x8086) 09:50:22 INFO - Device ID: 0x0a2e 09:50:22 INFO - Revision ID: 0x0009 09:50:22 INFO - Displays: 09:50:22 INFO - Display: 09:50:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:50:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:50:22 INFO - Display Serial Number: 1600x1200 60 09:50:22 INFO - Main Display: Yes 09:50:22 INFO - Mirror: Off 09:50:22 INFO - Online: Yes 09:50:22 INFO - Rotation: Supported 09:50:22 INFO - Return code: 0 09:50:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:50:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:50:22 INFO - Python 2.7.3 09:50:22 INFO - Return code: 0 09:50:22 INFO - grabbing minidump binary from tooltool 09:50:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:50:22 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: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101effd30>, '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 0x101d65db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101ef7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:50:22 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:50:22 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:50:22 INFO - Calling ['/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'] with output_timeout 600 09:50:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:50:22 INFO - Return code: 0 09:50:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:50:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:50:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:50:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:50:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:50:22 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', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 09:50:22 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 --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0133 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 09:50:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.u5y4IdXHyj/Render', 09:50:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:50:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:50:22 INFO - 'HOME': '/Users/cltbld', 09:50:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:50:22 INFO - 'LOGNAME': 'cltbld', 09:50:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:50:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:50:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:50:22 INFO - 'MOZ_NO_REMOTE': '1', 09:50:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:50:22 INFO - 'NO_EM_RESTART': '1', 09:50:22 INFO - 'PAGER': '/bin/cat', 09:50:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:50:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:50:22 INFO - 'PWD': '/builds/slave/test', 09:50:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:50:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:50:22 INFO - 'SHELL': '/bin/bash', 09:50:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.72g48FAOQc/Listeners', 09:50:22 INFO - 'TMPDIR': '/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/', 09:50:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:50:22 INFO - 'USER': 'cltbld', 09:50:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:50:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:50:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:50:22 INFO - 'XPC_FLAGS': '0x0', 09:50:22 INFO - 'XPC_SERVICE_NAME': '0', 09:50:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:50:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0133', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 09:50:22 INFO - mozversion application_buildid: 20161117071828 09:50:22 INFO - mozversion application_changeset: 97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e 09:50:22 INFO - mozversion application_display_name: FirefoxDeveloperEdition 09:50:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:50:22 INFO - mozversion application_name: Firefox 09:50:22 INFO - mozversion application_remotingname: firefox-dev 09:50:22 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:50:22 INFO - mozversion application_vendor: Mozilla 09:50:22 INFO - mozversion application_version: 52.0a2 09:50:22 INFO - mozversion platform_buildid: 20161117071828 09:50:22 INFO - mozversion platform_changeset: 97f55b49a6d74b9e871a4cc0a8b3d793a5e9a27e 09:50:22 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 09:50:22 INFO - mozversion platform_version: 52.0a2 09:50:22 INFO - using testdate: 1479405022 09:50:22 INFO - actual date: 1479405022 09:50:22 INFO - starting webserver on 'localhost:56923' 09:50:22 INFO - SUITE-START | Running 2 tests 09:50:22 INFO - TEST-START | dromaeo_css 09:50:22 INFO - operating with platform_type : mac_ 09:50:22 INFO - Initialising browser for dromaeo_css test... 09:50:23 INFO - TEST-INFO | started process 2175 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpUtPvqW/profile http://localhost:56923/getInfo.html) 09:50:24 ERROR - PROCESS | 2175 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 09:50:25 INFO - PROCESS | 2175 | __metrics Screen width/height:1600/1200 09:50:25 INFO - PROCESS | 2175 | colorDepth:24 09:50:25 INFO - PROCESS | 2175 | Browser inner width/height: 1024/689 09:50:25 INFO - PROCESS | 2175 | __metrics 09:50:25 INFO - PROCESS | 2175 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:50:25 INFO - PROCESS | 2175 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:50:26 INFO - TEST-INFO | 2175: exit 0 09:50:26 INFO - Browser initialized. 09:50:26 INFO - Running cycle 1/1 for dromaeo_css test... 09:50:26 INFO - TEST-INFO | started process 2177 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpUtPvqW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:50:38 INFO - PROCESS | 2177 | RSS: Main: 217251840 09:50:38 INFO - PROCESS | 2177 | 09:51:29 INFO - PROCESS | 2177 | 1479405089579 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:53:40 INFO - PROCESS | 2177 | Cycle 1(1): loaded http://localhost:56923/tests/dromaeo/cssquery-dojo.html (next: http://localhost:56923/tests/dromaeo/cssquery-ext.html) 09:53:44 INFO - PROCESS | 2177 | RSS: Main: 516571136 09:53:44 INFO - PROCESS | 2177 | 09:56:47 INFO - PROCESS | 2177 | Cycle 1(1): loaded http://localhost:56923/tests/dromaeo/cssquery-ext.html (next: http://localhost:56923/tests/dromaeo/cssquery-jquery.html) 09:56:48 INFO - PROCESS | 2177 | RSS: Main: 351780864 09:56:48 INFO - PROCESS | 2177 | 09:59:50 INFO - PROCESS | 2177 | Cycle 1(1): loaded http://localhost:56923/tests/dromaeo/cssquery-jquery.html (next: http://localhost:56923/tests/dromaeo/cssquery-mootools.html) 09:59:51 INFO - PROCESS | 2177 | RSS: Main: 320798720 09:59:51 INFO - PROCESS | 2177 | 10:02:53 INFO - PROCESS | 2177 | Cycle 1(1): loaded http://localhost:56923/tests/dromaeo/cssquery-mootools.html (next: http://localhost:56923/tests/dromaeo/cssquery-prototype.html) 10:02:54 INFO - PROCESS | 2177 | RSS: Main: 329723904 10:02:54 INFO - PROCESS | 2177 | 10:05:57 INFO - PROCESS | 2177 | Cycle 1(1): loaded http://localhost:56923/tests/dromaeo/cssquery-prototype.html (next: http://localhost:56923/tests/dromaeo/cssquery-yui.html) 10:05:58 INFO - PROCESS | 2177 | RSS: Main: 314298368 10:05:58 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | Cycle 1(1): loaded http://localhost:56923/tests/dromaeo/cssquery-yui.html (next: http://localhost:56923/tests/dromaeo/cssquery-dojo.html) 10:09:01 INFO - PROCESS | 2177 | RSS: Main: 427302912 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | __start_tp_report 10:09:01 INFO - PROCESS | 2177 | _x_x_mozilla_page_load 10:09:01 INFO - PROCESS | 2177 | _x_x_mozilla_page_load_details 10:09:01 INFO - PROCESS | 2177 | |i|pagename|runs| 10:09:01 INFO - PROCESS | 2177 | |0;dojo.html;3264.918377040574;3265.5591495148165;3276.8853090141843;3281.1961069537965;3291.1772056985756;5554.750036248369;5581.441855814416;5612.494875461208;5614.410486898873;5633;5540.3628582713;5552.77788888444;5556.138798486241;5579.721013949296;5601;5051;5059.620528460362;5086.491350864902;5164.096283150452;5179.2749015137915;3973.3046716824583;4127.318992366256;5077.314562534048;5085.644004919657;5128.743562821844;5080.2125670520945;5152.098382783017;5152.407473140581;5155.20094385369;5168.948310516885;5149.995750828609;5164.147915593922;5209.791608335662;5237.502437268454;5281.445448227919;4932.679375840559;5145.485451454862;5154.896902061938;5168.896622067575;5174.120399532088;5474.7810087596445;5486.506214440679;5495.725213739337;5537.390887002426;5546.389897111315;6039.245094363205;6058.3032951210735;6062.848428789272;6131.294901086343;6132.202813634243;6154.72303746328;6217.906731399033;6220.03589443637;6237.875242495124;6238.189035425411;5582.413846546095;5618.101103823368;5628.943710562924;5638.774449022075;5691.857703557362;5465.808696695596;6100;6240.781572644935;6289.213848268966;6301.180846490019;6025.0661147522205;6050.485708714808;6065.727042283108;6068.423499767515;6080.543959203077;5980.940190598125;6003.609765365237;6051.0923361496125;6109.5112391008615;6194.628322300677;6027.45752882234;6035.185249991283;6043.909341359795;6047.244094488189;6056.394360563909;6091.299500557493;6125.938740612626;6130.1038476501635;6139.829471381233;6185.721642526097;4871.3667223260745;4880.853574392774;4882.707037577757;4900.4854490278;4901.656884018084;4378.299472084451;4392.626626736699;4398.670099742532;4409.647228221735;4426.18115648606;5525.171224316305;5594.720263986866;5612.214289999403;5626.043572592669;5659.490645841894;6163.075538669146;6210.472109870711;6225.875482490324;6226.159468471789;6232.906506402408;5674.06377947634;5725.484706376446;5727.169560413717;5738.655680659173;5762.366139724627;4450.421445212167;5199.298094757235;5338.786448542015;5339.679619222859;5341.038613049688;6220.315765265726;6253.593516421418;6265.154204182377;6268.623882567061;6275.02737075754;6128.53367901495;6202.596831205957;6226.844328891814;6227.003679411454;6233.688315584112;6290.9056364155485;6293.748250069946;6300.306966233802;6321.841953951188;6324.2410910691015;5880.088586269135;5938.762449501972;6102.450779429695;6105.755769769159;6124.54065945047;6107.795737660903;6110.052941794029;6113.083037544404;6137.263528376623;6140.631562106288;5871.6183448075735;5874.17761513397;5875.6474611523445;5877.735501902339;5893.351731309638;5652.491275785199;5861.794837180678;5879.382664820303;5885.14665373527;5897.970510147422;5734.197212390352;5744.965906136768;5748.712564371681;5756.481916627524;5779.335376431764;5762.971185144047;5893.734781934738;5921.2006379138265;5921.526277897844;5924.52603791687;5717;5745.195672605755;5777.364489906023;5777.971110144423;5782.017057100387 10:09:01 INFO - PROCESS | 2177 | |1;ext.html;452.6039715249156;457.3688310132017;461.8406649705851;502.19899260679205;554.2296208270504;38980.805095974516;40322.991925201844;40417.59582404179;42497.78751106248;42516.36225456621;41628.58371416292;42026.36960445588;42050;42132.789336053276;42170.156596868044;8560.529170895597;9333.53332333384;9509.667161649344;9522.238220942343;9530.094641009127;8710.782230444258;11417.94291028548;11686.707832304219;11693.707657308592;11877.465514051844;11469.655910322663;11512.539498420054;11516.251443656136;11563.479643416067;11589.536418543248;8682.913170868274;8901.73294801157;11404.71488212793;11518.827217591785;11519.42402879861;8716.302695784321;8777.561121943878;8778.034416214212;11149.498272577754;11459.656210313704;39245.80377098125;39406.211875762325;39557.80221098905;39565.60434395648;39668.801655991825;3453.4647129694936;3503.8598456061727;3644.6173151818944;3826.0626146593986;3907.609239076098;28702.282442938886;29805.10584682463;30170.245743856358;30472.63930263721;30641.387172256655;3167.8099314041;3191.776575639706;3229.951550726741;3260.152360386293;3273.623533293654;486936;487050;487202;488162.55918720533;494050;58981.11528327079;59108;59126;59200.7039964806;59236.40763592395;62282.688586556775;62300;62380.376196237456;62542.37457625366;62660;15518.379264829506;16097;16124.919375403048;16146.273417696231;16153.838461615469;11249.268797528135;11523.942380288045;11532.59635912739;12528.997680185563;12594.748105037848;15039.548813535612;15152.242387880562;15173.544793656209;16659.00045997244;16960.982341059575;5884.676342801187;6029.336772954972;6030.095485677183;6081.878362432726;6083.087536869417;858.5406807358033;880.9207171354481;926.0831776541175;1015.2639336480992;1016.9880968436453;28277.43445131086;28815.99144029991;29184;29503.409931801245;29529.85235073811;868.4658934755206;1024.8155332040305;1042.2547878267076;1051.6161601015574;1053.7997780421695;15397.615059623375;15504;15523.379064837505;15686.372545098297;15788.368465261261;611504;643364;644837;645861;647532;56773.71613141908;56921.715391422775;56960.43039569634;56971.57571060757;57080.85838283211;1360.1702961193664;1368.7399394115087;1382.3433868912186;1385.819843420349;1389.388668985643;28526.714732852404;28818.423631527672;28982.27544311325;29685.554716678835;29721.9194656372;41794.79102604467;50065.49934500754;50469.74765126151;50626.49373506218;50638.746806265735;462304;463034;464290;464367;465568;39730.205395892495;40385.798071009456;40652.59347406488;40686;40891;45052.32421513746;45617.54382456133;45666;46258.8435289107;46702.299465508724;37240.813795930844;37435.6256437443;37507.24985500329;37555.81222093981;37772.43341349937;36278.58731536043;37520.437193441576;38585.2282954345;38910.02724931899;39038.219235615696;36764.26471470609;36808.89573312805;36956.81521592375;37077.073073173386;37204.255914882095;31496.212594685316;33853.492197616564;33933.8123165688;34149.82925085458;34306.65693343035;24625.384365391008;25077.74922250754;25288.873555632104;25682;25691.100811471508 10:09:01 INFO - PROCESS | 2177 | |2;jquery.html;350.7106637024456;368.285526079406;376.1499012232354;384.4137690022716;401.945737325461;14593.854061459382;14782.55652330429;15921.442749503783;20236.291729789424;20460.07929643166;18999.62000759984;19438.319658811943;19946.90026549865;20154.596908061867;20243.49391265219;5750.712464376786;5985.431384018511;6051.6671583063135;6058.969975104243;6074.757009719606;5726.141078838166;5956.851078723045;6015.939840601582;6020.5550667839625;6020.698965051752;5679.432056794308;5927.525797936155;5946.22699049123;5949.077892926603;5951.732172052268;5911.3201981772;6104.267487901436;6105.481034112084;6106.175666285084;6109.755609775603;5667.376588575254;5803.484681650346;5867.11993201019;5913.615614985012;5929.288485381739;10733.516991735312;13446.260455674934;13480.5281815136;14645.707085858223;25325.493490130277;2059.5571952030236;2250.144944920936;2344.6717459555507;2517.546841568517;2578.651881995944;9932.155766759834;10227.642032528825;10253.025962533547;12531.686707832287;14622.926885365612;1849.5006348285888;1855.452641470756;1893.8579606529427;1904.2002359009111;1938.0890981238795;113857;117427;118407.81592184053;120169.79830201673;124194;11225.943870280596;11393.829092563617;11415.714607134722;11473.196876218752;11632.418379081013;14495.130292182503;14498.492552760603;14503.709925801424;14532.27338633064;14540.636484087983;8046.959765201136;8298.875516867252;8367.456115352483;8468.280196183394;8471.745847624581;7918.366530677532;8229.917700823034;8248.752537423767;8402.201790829862;8415.915840841513;3907.296686596383;4333.848315308949;4460.821567137278;4559.772011399483;4560.703554269028;1776.8578513718871;1827.643609496162;1828.067685480388;1835.7328699056654;1837.6784062788959;850.46420754924;901.2114399900088;901.8917729872458;908.2869947091633;963.7012526116927;19476.70784938227;20692.275770348268;20891.58216835685;21100.683489747666;21220.469488262916;914.9679761208325;949.3070058856935;956.4118067388506;971.6259240192577;980.6273616025865;10507.47462626881;11255.380954047443;11263.549458021751;11299.54801807935;11303.830442543369;593769;601686;608851;620113;622367;85853;94682;98475;104163;122899;887.9289656827439;944.0889541592534;994.8955359687128;996.342414006752;996.7308826616918;18633.440996770118;18886.905565472083;19290.4212873614;19358.128884199963;19427.8057219426;44603.77698111489;55480.6129846757;55811;56679.71660141673;56978.43021569731;58978.70510647419;72696.6365168192;73767;76191.8571221421;77846.6107669458;10715.142788577052;12093.516259349528;12129.272243665408;12181.512739490321;12592.055595830167;13188.80216796768;13535.458581656623;13573.253471059186;13964.720705586034;14025.929870350583;10915.563377464812;11541.538338466704;11651.650450486499;11789.941050294694;11957.342346171044;11275;11455.484503197542;11661.825072623747;11835.11236657237;11988.76022479528;11677.474513647076;11725.589604363806;11899.702507437383;11920.344381059125;12204.948762809297;9116.589753461243;11189.160812939224;12066.879331206575;12360;12385.256884586954;9687.034277198212;11580.305181689126;11613.825792612948;11813.054955603358;12063.276203427822 10:09:01 INFO - PROCESS | 2177 | |3;mootools.html;1467.1490535489413;1485.1163557683178;1654.3051918194358;1807.2319264312664;1825.0418530271613;8213.712520061792;8626.439281446717;8696.04343522212;8709.21617054465;8711.69509067182;7565.394768418527;7859.410544209179;7860.92139078609;7957.641906114224;7965.641546130423;1889.7165425186263;2116.566103948691;2376.584097782881;2380.6191009438494;2400.423898264413;2155.8059774620283;2297.597920363938;2388.6894703688495;2405.3625789165885;2410.276916924924;2369.6327069304198;2392.055138220402;2393.640953856918;2397.5564520563753;2403.735589085199;2340.5201933603657;2386.522695460906;2390.151496218835;2392.892319845611;2399.784019438259;2395.6646069550275;2397.7602239776056;2405.494846082325;2416.830821842454;2417.6615273861676;8445.619947102396;8622.267107295887;8631.827363452758;8640.956795215983;8641.395102342838;5172.560332371753;5344.465553444663;5348.652337598043;5352.892942141174;5362.865928351784;3405.2338223899674;3611.6388361163936;3671.70626349894;3676.4669122977284;3679.208970071421;1025.4155131575005;1059.7774467361862;1104.536094840169;1110.9222354435192;1114.4706264524368;10769;11114.55541778336;11269.887301127048;11280.984711376015;11416.31502109876;4429.623482004065;4515.593596576258;4539.387182730356;4552.271636538138;4566.269396896547;7903.525788452711;8799.076097009722;8889.599968001456;8958.910410895938;8961.507117108622;5181.145111056709;5182.196759502283;5190.091733946544;5190.117679994334;5196.662216955886;4383.342498625232;4712.64655150865;4861.6353773467135;4895.265710143507;4923.926141107886;3460.1003739028033;3568.0901370150445;3648.4892115103908;3793.81030948457;3794.905127371783;1141.937193454368;1173.724174818917;1174.8237764335418;1180.9704757381135;1184.1178322150054;866.6013633728555;887.5843030366555;899.6176624934508;901.0043901488891;903.6656437118309;5535.723213839292;5715.457031581993;5745.597808153473;5815.651060936358;5967.910481342783;895.8611415230649;902.264654306738;906.2297047509696;912.6805618033635;954.8854137503544;3328.4508056170944;3737.4767532545466;3745.3445647011663;3843.6540711335556;3978.508059477696;10018.64934727281;10284.794304114026;10419.583216671399;10427.530761115768;10459.215558833119;10381.688549343531;10382.844257336146;10383.9480802597;10389.584416623402;10394.428306443067;7850.2934735872905;7988.201179882081;8039.758807235792;8048.637811298506;8061.355091592659;5482.506574408322;5668.914966275592;5680.573956953328;5849.502792262535;5959.195508606283;8255.298299644357;9494;9509;9610.087041731025;9660.985596512264;9380.48407337603;9715.125638692552;9859;9861.753456163653;9865.704028879145;7262.891056634049;7399.8150046249275;7404.592747398729;7406.851862962818;7410.592417417094;7270.709171633075;7331.120265567953;7588.734394296394;7598.96200519016;8069.556174410463;6967.163940327193;7245.203027666847;7284.927150728637;7313.085864266966;7417;6472.093906852951;6495.447886929475;6864.931350686429;7096.929030709627;7180.5691658500655;6216.53375996793;6772.356626120511;6803.25164231944;6854.451643868579;6901.861962760817;4811.663183577116;4838.758062096811;4873.171560834596;4911.582515486224;5064.139096353555;4931.3835770528685;4948.331975183448;5007.874803129951;5018.272350509229;5058.974705126598 10:09:01 INFO - PROCESS | 2177 | |4;prototype.html;300.63022482346713;307.9399517094166;310.6272473032361;315.34565776014773;316.9683031696832;4276.572342765726;4318.8488402905905;4938.728369939652;5137.152369858978;5206.277511579225;4240.512341080776;4910.772306923269;5129.589632829374;5184.326037615105;5203.271541984116;3297.389982853168;3675.9891029966707;3785.186184970231;3855.614438556136;3901.629345212207;3929.764214147146;3947.8618248361313;3971.523417189941;3979.4929194607357;3987.7806720630356;3284.868605255787;3938.8030598470104;3944.23087497938;3948.9012774680577;3983.4423180754716;3540.91147721309;3645.9453108203265;3920.941185882214;3931.528216613996;3953.3081710700512;3434.553508043963;3442.6557344265375;3457.5850897892165;3459.550258466383;3476.7392445566684;4936.901261974776;4946.5795407390415;4957.851264462072;4965.950340496585;4978.576820970222;3586.6234045425376;3745.887623371303;4287.892802679927;4446.822127114911;4453.481749417956;4520.163769702615;4667.369905062841;4784.617230621542;4917.262410638397;5341.065313570149;3345.7323414126813;3708.5364329458816;3763.360228761117;3764.0589852536864;3769.264993326275;4446.777661116931;5022.8196373852115;5678.7790625015405;5757.568182386296;5773.566982476289;3676.5771936227143;3737.9439308410397;3744.8314825832904;3753.399456087013;3759.0414444316525;3587.9727953706074;3620.9818950905606;3760.830762615689;3793.715471339661;3832.4549791442805;3728.5339332583426;4433.866983990485;4458.9298568343365;4463.866084017484;4504.25679762842;4488.573585509436;4983.725895075806;5410.431904650034;5424.213489044067;5526.281583394213;1151.1826603111883;1198.9760204795855;1240.2124650846777;1242.7079636285466;1243.533674871923;718.8346680263569;781.9335356494762;839.8152406470567;852.7081471570575;854.4531499840107;4413.360062790942;4715.363425937523;4933.827316043993;5037.924431133536;5079.771410286546;4924.556789888928;5558.6942718150085;5581.665100094007;5598.7962588043765;5700.885982280414;4360.847370342086;4521.412216411911;4761.809587576332;4842;5204.219367094966;2497.600383938561;2497.9125730599344;2641.0492222799776;2719.265798234466;2810.737356428054;4394.406755088086;4705.341252224691;4956.265307142471;4970.453250142481;5472.671639701631;4605.263157894788;5031.471695471996;5280.468664087449;5365.919511207335;5480.437715484887;4867.780949857265;5036.597072234213;5155.304033955518;5284.916592098611;5376.274202982626;4603.930941035954;5234.738263086754;5268.892443022668;5310.787568497217;5438.918416223681;4408.933865992077;4478.395416618715;4704.035672687022;5066.721330326867;5205.895882082262;4852.296417019442;5370.9590355664595;5435.456454354533;5440.891182176255;5445.618806683494;3217.9356412871143;3222.6455089940555;3403.5745531808525;3568.803715795656;3602.47764074213;4405.709031303768;4897.804087836446;4974.477679843564;5054.671446355975;5256.316678831728;4683.882902927454;4839.733814640229;4937.876553086202;5110.795568177231;5146.221040638163;3984.980075099722;4710.764461776966;5144.202648589475;5189.351331083614;5225.973870130625;4215.557366476476;4598.149342371672;4711.8771075309105;4922.227997920817;5031.20767973778;4894.632902532396;5265.710385928811;5279.39286982002;5295.920561191662;5302.2576839241765;4322.092360604308;4653.604443622195;4890.483949975519;5155.839936014367;5283.65656232359 10:09:01 INFO - PROCESS | 2177 | |5;yui.html;692.390696187355;706.2161001288572;718.5185925429961;747.5402627384157;757.4508481351018;13473.124246923955;14083;14118.717625647494;14122.929385353085;14131.364088616012;12886.420111095;13027.218366897992;13040.80438793419;13104.73790524189;13110.147840390367;633.1831936801532;654.116942128128;788.6451097006342;812.695239285268;823.6540652925786;799.9320057795095;810.0401024785618;812.3622955979557;816.0166998766501;817.9141190175035;863.4258218284833;864.8789169516241;865.4807115730574;866.6576702202626;867.3364875869964;624.5035197018336;632.171854870117;667.8397184675691;860.3633311349619;862.659249596409;510.1582389058047;510.51246060012596;617.5646169450526;639.6226226526372;642.2197030607787;14117.929410352883;14143.504977325847;14152.504662336769;14156.646083847883;14158.858411415857;12897.67755806103;12994.28531430771;13179;13196;13274.601761947157;11789.233699809485;12094.153409261355;12119;12180.51277948881;12276.202046867014;4211.831526738927;4254.851080212208;4289.892752681208;4346.1090476452255;4517.073999830027;87228;87730;88124.55937720335;88144.55927720385;89057.55471222602;9495.857562136573;9561.760955976157;10106.848397274047;10119.342242754197;10156.085952264331;19263.51841203953;19273.132709028127;19793.20827166926;19916.103775329855;19926.103325350396;7228.855422891513;7323.853522929511;7372.373348265458;7555.24447555251;7603.7338693145475;4029.6373326400767;4085.346344584852;4165.854195103157;4216.840368898516;4275.251830929575;3235.6602556731673;3289;3325.0523600774145;3327.101682545699;3337.9499307510405;608.7777961044187;622.9408206220402;631.390707966805;632.3486808587071;639.3766078073841;522.5062316111314;606.3118360660594;614.631221267245;630.7035693224177;637.2321352769901;8216.876746848802;8594.183552562497;8693.782655433539;8731.170538798804;8873.157050080232;600.6366148480193;616.7563812294119;622.8380621038563;626.9404406581367;645.7933461292436;4105.609967053125;4181.519125300569;4189.141226048653;4299.935500967488;4371.584699453547;114979;115360.42319788515;115596.42201788937;117268.24097638542;118273.40863295629;77402;91971.5401423002;102909;105041.94958050322;107412.46293768482;59412.10881836776;59732.10401843976;84006;87162.56418717866;89260.6610900824;8036;8076.27313541784;8122.959385203272;8125.065617454068;8503.574821259035;55828.720856395455;57634.84730305454;59357.70321148367;60401.657825929404;61839;78014;84048.57975710083;88103;88391;88608;10678.62624808128;10701.51843167075;10732.570697172338;11032.834507482556;11614;10076.848847267443;11355.318680879174;11368.658940231806;11557.82663260035;12050.75898482043;11220.831687524857;11494.54021839117;11588.072954163816;11597.710057248636;12041.976432003028;10868.51091700891;10941.781164376509;11186.161037922026;11426;11926.224795388272;11130.276532025393;11250.66248012561;11362.488688009225;11390.25963312382;11605.81628003739;9385.530723463662;9611;9619.134277915022;9679;10090.19278457707;8355.415120941476;8606.78483037929;8610;8662.220400164015;8743.868841967249 10:09:01 INFO - PROCESS | 2177 | __end_tp_report 10:09:01 INFO - PROCESS | 2177 | __start_cc_report 10:09:01 INFO - PROCESS | 2177 | _x_x_mozilla_cycle_collect,3511 10:09:01 INFO - PROCESS | 2177 | __end_cc_report 10:09:01 INFO - PROCESS | 2177 | __startTimestamp1479406141408__endTimestamp 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | ------- Summary: start ------- 10:09:01 INFO - PROCESS | 2177 | Number of tests: 6 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | [#0] dojo.html Cycles:165 Average:5621.42 Median:5762.37 stddev:652.08 (11.3%) stddev-sans-first:627.33 10:09:01 INFO - PROCESS | 2177 | Values: 3264.9 3265.6 3276.9 3281.2 3291.2 5554.8 5581.4 5612.5 5614.4 5633.0 5540.4 5552.8 5556.1 5579.7 5601.0 5051.0 5059.6 5086.5 5164.1 5179.3 3973.3 4127.3 5077.3 5085.6 5128.7 5080.2 5152.1 5152.4 5155.2 5168.9 5150.0 5164.1 5209.8 5237.5 5281.4 4932.7 5145.5 5154.9 5168.9 5174.1 5474.8 5486.5 5495.7 5537.4 5546.4 6039.2 6058.3 6062.8 6131.3 6132.2 6154.7 6217.9 6220.0 6237.9 6238.2 5582.4 5618.1 5628.9 5638.8 5691.9 5465.8 6100.0 6240.8 6289.2 6301.2 6025.1 6050.5 6065.7 6068.4 6080.5 5980.9 6003.6 6051.1 6109.5 6194.6 6027.5 6035.2 6043.9 6047.2 6056.4 6091.3 6125.9 6130.1 6139.8 6185.7 4871.4 4880.9 4882.7 4900.5 4901.7 4378.3 4392.6 4398.7 4409.6 4426.2 5525.2 5594.7 5612.2 5626.0 5659.5 6163.1 6210.5 6225.9 6226.2 6232.9 5674.1 5725.5 5727.2 5738.7 5762.4 4450.4 5199.3 5338.8 5339.7 5341.0 6220.3 6253.6 6265.2 6268.6 6275.0 6128.5 6202.6 6226.8 6227.0 6233.7 6290.9 6293.7 6300.3 6321.8 6324.2 5880.1 5938.8 6102.5 6105.8 6124.5 6107.8 6110.1 6113.1 6137.3 6140.6 5871.6 5874.2 5875.6 5877.7 5893.4 5652.5 5861.8 5879.4 5885.1 5898.0 5734.2 5745.0 5748.7 5756.5 5779.3 5763.0 5893.7 5921.2 5921.5 5924.5 5717.0 5745.2 5777.4 5778.0 5782.0 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | [#1] ext.html Cycles:180 Average:67077.72 Median:21205.65 stddev:142985.49 (674.3%) stddev-sans-first:143299.10 10:09:01 INFO - PROCESS | 2177 | Values: 452.6 457.4 461.8 502.2 554.2 38980.8 40323.0 40417.6 42497.8 42516.4 41628.6 42026.4 42050.0 42132.8 42170.2 8560.5 9333.5 9509.7 9522.2 9530.1 8710.8 11417.9 11686.7 11693.7 11877.5 11469.7 11512.5 11516.3 11563.5 11589.5 8682.9 8901.7 11404.7 11518.8 11519.4 8716.3 8777.6 8778.0 11149.5 11459.7 39245.8 39406.2 39557.8 39565.6 39668.8 3453.5 3503.9 3644.6 3826.1 3907.6 28702.3 29805.1 30170.2 30472.6 30641.4 3167.8 3191.8 3230.0 3260.2 3273.6 486936.0 487050.0 487202.0 488162.6 494050.0 58981.1 59108.0 59126.0 59200.7 59236.4 62282.7 62300.0 62380.4 62542.4 62660.0 15518.4 16097.0 16124.9 16146.3 16153.8 11249.3 11523.9 11532.6 12529.0 12594.7 15039.5 15152.2 15173.5 16659.0 16961.0 5884.7 6029.3 6030.1 6081.9 6083.1 858.5 880.9 926.1 1015.3 1017.0 28277.4 28816.0 29184.0 29503.4 29529.9 868.5 1024.8 1042.3 1051.6 1053.8 15397.6 15504.0 15523.4 15686.4 15788.4 611504.0 643364.0 644837.0 645861.0 647532.0 56773.7 56921.7 56960.4 56971.6 57080.9 1360.2 1368.7 1382.3 1385.8 1389.4 28526.7 28818.4 28982.3 29685.6 29721.9 41794.8 50065.5 50469.7 50626.5 50638.7 462304.0 463034.0 464290.0 464367.0 465568.0 39730.2 40385.8 40652.6 40686.0 40891.0 45052.3 45617.5 45666.0 46258.8 46702.3 37240.8 37435.6 37507.2 37555.8 37772.4 36278.6 37520.4 38585.2 38910.0 39038.2 36764.3 36808.9 36956.8 37077.1 37204.3 31496.2 33853.5 33933.8 34149.8 34306.7 24625.4 25077.7 25288.9 25682.0 25691.1 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | [#2] jquery.html Cycles:180 Average:34429.12 Median:20791.93 stddev:100991.32 (485.7%) stddev-sans-first:101242.21 10:09:01 INFO - PROCESS | 2177 | Values: 350.7 368.3 376.1 384.4 401.9 14593.9 14782.6 15921.4 20236.3 20460.1 18999.6 19438.3 19946.9 20154.6 20243.5 5750.7 5985.4 6051.7 6059.0 6074.8 5726.1 5956.9 6015.9 6020.6 6020.7 5679.4 5927.5 5946.2 5949.1 5951.7 5911.3 6104.3 6105.5 6106.2 6109.8 5667.4 5803.5 5867.1 5913.6 5929.3 10733.5 13446.3 13480.5 14645.7 25325.5 2059.6 2250.1 2344.7 2517.5 2578.7 9932.2 10227.6 10253.0 12531.7 14622.9 1849.5 1855.5 1893.9 1904.2 1938.1 113857.0 117427.0 118407.8 120169.8 124194.0 11225.9 11393.8 11415.7 11473.2 11632.4 14495.1 14498.5 14503.7 14532.3 14540.6 8047.0 8298.9 8367.5 8468.3 8471.7 7918.4 8229.9 8248.8 8402.2 8415.9 3907.3 4333.8 4460.8 4559.8 4560.7 1776.9 1827.6 1828.1 1835.7 1837.7 850.5 901.2 901.9 908.3 963.7 19476.7 20692.3 20891.6 21100.7 21220.5 915.0 949.3 956.4 971.6 980.6 10507.5 11255.4 11263.5 11299.5 11303.8 593769.0 601686.0 608851.0 620113.0 622367.0 85853.0 94682.0 98475.0 104163.0 122899.0 887.9 944.1 994.9 996.3 996.7 18633.4 18886.9 19290.4 19358.1 19427.8 44603.8 55480.6 55811.0 56679.7 56978.4 58978.7 72696.6 73767.0 76191.9 77846.6 10715.1 12093.5 12129.3 12181.5 12592.1 13188.8 13535.5 13573.3 13964.7 14025.9 10915.6 11541.5 11651.7 11789.9 11957.3 11275.0 11455.5 11661.8 11835.1 11988.8 11677.5 11725.6 11899.7 11920.3 12204.9 9116.6 11189.2 12066.9 12360.0 12385.3 9687.0 11580.3 11613.8 11813.1 12063.3 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | [#3] mootools.html Cycles:180 Average:5499.30 Median:5176.85 stddev:3015.67 (58.3%) stddev-sans-first:3008.90 10:09:01 INFO - PROCESS | 2177 | Values: 1467.1 1485.1 1654.3 1807.2 1825.0 8213.7 8626.4 8696.0 8709.2 8711.7 7565.4 7859.4 7860.9 7957.6 7965.6 1889.7 2116.6 2376.6 2380.6 2400.4 2155.8 2297.6 2388.7 2405.4 2410.3 2369.6 2392.1 2393.6 2397.6 2403.7 2340.5 2386.5 2390.2 2392.9 2399.8 2395.7 2397.8 2405.5 2416.8 2417.7 8445.6 8622.3 8631.8 8641.0 8641.4 5172.6 5344.5 5348.7 5352.9 5362.9 3405.2 3611.6 3671.7 3676.5 3679.2 1025.4 1059.8 1104.5 1110.9 1114.5 10769.0 11114.6 11269.9 11281.0 11416.3 4429.6 4515.6 4539.4 4552.3 4566.3 7903.5 8799.1 8889.6 8958.9 8961.5 5181.1 5182.2 5190.1 5190.1 5196.7 4383.3 4712.6 4861.6 4895.3 4923.9 3460.1 3568.1 3648.5 3793.8 3794.9 1141.9 1173.7 1174.8 1181.0 1184.1 866.6 887.6 899.6 901.0 903.7 5535.7 5715.5 5745.6 5815.7 5967.9 895.9 902.3 906.2 912.7 954.9 3328.5 3737.5 3745.3 3843.7 3978.5 10018.6 10284.8 10419.6 10427.5 10459.2 10381.7 10382.8 10383.9 10389.6 10394.4 7850.3 7988.2 8039.8 8048.6 8061.4 5482.5 5668.9 5680.6 5849.5 5959.2 8255.3 9494.0 9509.0 9610.1 9661.0 9380.5 9715.1 9859.0 9861.8 9865.7 7262.9 7399.8 7404.6 7406.9 7410.6 7270.7 7331.1 7588.7 7599.0 8069.6 6967.2 7245.2 7284.9 7313.1 7417.0 6472.1 6495.4 6864.9 7096.9 7180.6 6216.5 6772.4 6803.3 6854.5 6901.9 4811.7 4838.8 4873.2 4911.6 5064.1 4931.4 4948.3 5007.9 5018.3 5059.0 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | [#4] prototype.html Cycles:180 Average:4212.38 Median:4675.63 stddev:1266.60 (27.1%) stddev-sans-first:1235.65 10:09:01 INFO - PROCESS | 2177 | Values: 300.6 307.9 310.6 315.3 317.0 4276.6 4318.8 4938.7 5137.2 5206.3 4240.5 4910.8 5129.6 5184.3 5203.3 3297.4 3676.0 3785.2 3855.6 3901.6 3929.8 3947.9 3971.5 3979.5 3987.8 3284.9 3938.8 3944.2 3948.9 3983.4 3540.9 3645.9 3920.9 3931.5 3953.3 3434.6 3442.7 3457.6 3459.6 3476.7 4936.9 4946.6 4957.9 4966.0 4978.6 3586.6 3745.9 4287.9 4446.8 4453.5 4520.2 4667.4 4784.6 4917.3 5341.1 3345.7 3708.5 3763.4 3764.1 3769.3 4446.8 5022.8 5678.8 5757.6 5773.6 3676.6 3737.9 3744.8 3753.4 3759.0 3588.0 3621.0 3760.8 3793.7 3832.5 3728.5 4433.9 4458.9 4463.9 4504.3 4488.6 4983.7 5410.4 5424.2 5526.3 1151.2 1199.0 1240.2 1242.7 1243.5 718.8 781.9 839.8 852.7 854.5 4413.4 4715.4 4933.8 5037.9 5079.8 4924.6 5558.7 5581.7 5598.8 5700.9 4360.8 4521.4 4761.8 4842.0 5204.2 2497.6 2497.9 2641.0 2719.3 2810.7 4394.4 4705.3 4956.3 4970.5 5472.7 4605.3 5031.5 5280.5 5365.9 5480.4 4867.8 5036.6 5155.3 5284.9 5376.3 4603.9 5234.7 5268.9 5310.8 5438.9 4408.9 4478.4 4704.0 5066.7 5205.9 4852.3 5371.0 5435.5 5440.9 5445.6 3217.9 3222.6 3403.6 3568.8 3602.5 4405.7 4897.8 4974.5 5054.7 5256.3 4683.9 4839.7 4937.9 5110.8 5146.2 3985.0 4710.8 5144.2 5189.4 5226.0 4215.6 4598.1 4711.9 4922.2 5031.2 4894.6 5265.7 5279.4 5295.9 5302.3 4322.1 4653.6 4890.5 5155.8 5283.7 10:09:01 INFO - PROCESS | 2177 | 10:09:01 INFO - PROCESS | 2177 | [#5] yui.html Cycles:180 Average:20522.00 Median:56731.78 stddev:31189.63 (55.0%) stddev-sans-first:31241.59 10:09:01 INFO - PROCESS | 2177 | Values: 692.4 706.2 718.5 747.5 757.5 13473.1 14083.0 14118.7 14122.9 14131.4 12886.4 13027.2 13040.8 13104.7 13110.1 633.2 654.1 788.6 812.7 823.7 799.9 810.0 812.4 816.0 817.9 863.4 864.9 865.5 866.7 867.3 624.5 632.2 667.8 860.4 862.7 510.2 510.5 617.6 639.6 642.2 14117.9 14143.5 14152.5 14156.6 14158.9 12897.7 12994.3 13179.0 13196.0 13274.6 11789.2 12094.2 12119.0 12180.5 12276.2 4211.8 4254.9 4289.9 4346.1 4517.1 87228.0 87730.0 88124.6 88144.6 89057.6 9495.9 9561.8 10106.8 10119.3 10156.1 19263.5 19273.1 19793.2 19916.1 19926.1 7228.9 7323.9 7372.4 7555.2 7603.7 4029.6 4085.3 4165.9 4216.8 4275.3 3235.7 3289.0 3325.1 3327.1 3337.9 608.8 622.9 631.4 632.3 639.4 522.5 606.3 614.6 630.7 637.2 8216.9 8594.2 8693.8 8731.2 8873.2 600.6 616.8 622.8 626.9 645.8 4105.6 4181.5 4189.1 4299.9 4371.6 114979.0 115360.4 115596.4 117268.2 118273.4 77402.0 91971.5 102909.0 105041.9 107412.5 59412.1 59732.1 84006.0 87162.6 89260.7 8036.0 8076.3 8123.0 8125.1 8503.6 55828.7 57634.8 59357.7 60401.7 61839.0 78014.0 84048.6 88103.0 88391.0 88608.0 10678.6 10701.5 10732.6 11032.8 11614.0 10076.8 11355.3 11368.7 11557.8 12050.8 11220.8 11494.5 11588.1 11597.7 12042.0 10868.5 10941.8 11186.2 11426.0 11926.2 11130.3 11250.7 11362.5 11390.3 11605.8 9385.5 9611.0 9619.1 9679.0 10090.2 8355.4 8606.8 8610.0 8662.2 8743.9 10:09:01 INFO - PROCESS | 2177 | -------- Summary: end -------- 10:09:01 INFO - PROCESS | 2177 | 10:09:02 INFO - TEST-INFO | 2177: exit 0 10:09:02 INFO - TEST-OK | dromaeo_css | took 1119106ms 10:09:02 INFO - TEST-START | kraken 10:09:02 INFO - operating with platform_type : mac_ 10:09:02 INFO - Initialising browser for kraken test... 10:09:02 INFO - TEST-INFO | started process 2180 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpuHD8M1/profile http://localhost:56923/getInfo.html) 10:09:03 ERROR - PROCESS | 2180 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 5376: TypeError: parent.firstChild is null 10:09:04 INFO - PROCESS | 2180 | __metrics Screen width/height:1600/1200 10:09:04 INFO - PROCESS | 2180 | colorDepth:24 10:09:04 INFO - PROCESS | 2180 | Browser inner width/height: 1024/689 10:09:04 INFO - PROCESS | 2180 | __metrics 10:09:04 INFO - PROCESS | 2180 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 10:09:04 INFO - PROCESS | 2180 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:09:05 INFO - TEST-INFO | 2180: exit 0 10:09:05 INFO - Browser initialized. 10:09:05 INFO - Running cycle 1/1 for kraken test... 10:09:05 INFO - TEST-INFO | started process 2181 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/tmpuHD8M1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:09:17 INFO - PROCESS | 2181 | RSS: Main: 217452544 10:09:17 INFO - PROCESS | 2181 | 10:09:20 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:56923/tests/kraken/driver.html?testName=audio-beat-detection) 10:09:21 INFO - PROCESS | 2181 | RSS: Main: 267055104 10:09:21 INFO - PROCESS | 2181 | 10:09:23 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:56923/tests/kraken/driver.html?testName=audio-dft) 10:09:24 INFO - PROCESS | 2181 | RSS: Main: 276996096 10:09:24 INFO - PROCESS | 2181 | 10:09:27 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:56923/tests/kraken/driver.html?testName=audio-fft) 10:09:28 INFO - PROCESS | 2181 | RSS: Main: 376172544 10:09:28 INFO - PROCESS | 2181 | 10:09:30 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:56923/tests/kraken/driver.html?testName=audio-oscillator) 10:09:30 INFO - PROCESS | 2181 | RSS: Main: 278618112 10:09:30 INFO - PROCESS | 2181 | 10:09:32 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:56923/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:09:33 INFO - PROCESS | 2181 | RSS: Main: 279883776 10:09:33 INFO - PROCESS | 2181 | 10:09:36 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:56923/tests/kraken/driver.html?testName=imaging-darkroom) 10:09:36 INFO - PROCESS | 2181 | RSS: Main: 320159744 10:09:36 INFO - PROCESS | 2181 | 10:09:39 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:56923/tests/kraken/driver.html?testName=imaging-desaturate) 10:09:40 INFO - PROCESS | 2181 | RSS: Main: 321191936 10:09:40 INFO - PROCESS | 2181 | 10:09:43 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:56923/tests/kraken/driver.html?testName=json-parse-financial) 10:09:43 INFO - PROCESS | 2181 | RSS: Main: 318255104 10:09:43 INFO - PROCESS | 2181 | 10:09:45 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:56923/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:09:46 INFO - PROCESS | 2181 | RSS: Main: 295440384 10:09:46 INFO - PROCESS | 2181 | 10:09:47 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:09:48 INFO - PROCESS | 2181 | RSS: Main: 296927232 10:09:48 INFO - PROCESS | 2181 | 10:09:50 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:09:51 INFO - PROCESS | 2181 | RSS: Main: 300253184 10:09:51 INFO - PROCESS | 2181 | 10:09:54 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:09:55 INFO - PROCESS | 2181 | RSS: Main: 316092416 10:09:55 INFO - PROCESS | 2181 | 10:09:58 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:09:58 INFO - PROCESS | 2181 | RSS: Main: 310157312 10:09:58 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | Cycle 1(1): loaded http://localhost:56923/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:56923/tests/kraken/driver.html?testName=ai-astar) 10:10:00 INFO - PROCESS | 2181 | RSS: Main: 353656832 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | __start_tp_report 10:10:00 INFO - PROCESS | 2181 | _x_x_mozilla_page_load 10:10:00 INFO - PROCESS | 2181 | _x_x_mozilla_page_load_details 10:10:00 INFO - PROCESS | 2181 | |i|pagename|runs| 10:10:00 INFO - PROCESS | 2181 | |0;ai-astar;115;113;116;114;114;114;114;115;113;113 10:10:00 INFO - PROCESS | 2181 | |1;audio-beat-detection;149;113;114;148;115;114;116;116;144;113 10:10:00 INFO - PROCESS | 2181 | |2;audio-dft;183;185;185;185;185;184;184;184;186;188 10:10:00 INFO - PROCESS | 2181 | |3;audio-fft;83;60;59;91;64;60;60;59;84;59 10:10:00 INFO - PROCESS | 2181 | |4;audio-oscillator;84;70;95;70;68;68;89;68;69;93 10:10:00 INFO - PROCESS | 2181 | |5;imaging-gaussian-blur;88;85;109;117;89;86;88;84;88;83 10:10:00 INFO - PROCESS | 2181 | |6;imaging-darkroom;96;97;96;97;96;97;95;101;95;96 10:10:00 INFO - PROCESS | 2181 | |7;imaging-desaturate;81;101;126;132;85;80;85;83;82;81 10:10:00 INFO - PROCESS | 2181 | |8;json-parse-financial;57;57;53;54;59;58;58;56;57;57 10:10:00 INFO - PROCESS | 2181 | |9;json-stringify-tinderbox;64;73;73;75;71;70;70;71;71;71 10:10:00 INFO - PROCESS | 2181 | |10;stanford-crypto-aes;88;83;85;82;83;88;84;83;81;86 10:10:00 INFO - PROCESS | 2181 | |11;stanford-crypto-ccm;141;156;151;148;145;147;145;152;218;177 10:10:00 INFO - PROCESS | 2181 | |12;stanford-crypto-pbkdf2;169;170;168;169;163;184;173;165;167;165 10:10:00 INFO - PROCESS | 2181 | |13;stanford-crypto-sha256-iterative;67;68;63;62;69;84;86;85;87;68 10:10:00 INFO - PROCESS | 2181 | __end_tp_report 10:10:00 INFO - PROCESS | 2181 | __start_cc_report 10:10:00 INFO - PROCESS | 2181 | _x_x_mozilla_cycle_collect,2023 10:10:00 INFO - PROCESS | 2181 | __end_cc_report 10:10:00 INFO - PROCESS | 2181 | __startTimestamp1479406200770__endTimestamp 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | ------- Summary: start ------- 10:10:00 INFO - PROCESS | 2181 | Number of tests: 14 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:0.99 (0.9%) stddev-sans-first:1.00 10:10:00 INFO - PROCESS | 2181 | Values: 115.0 113.0 116.0 114.0 114.0 114.0 114.0 115.0 113.0 113.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#1] audio-beat-detection Cycles:10 Average:124.20 Median:116.00 stddev:15.82 (13.6%) stddev-sans-first:14.00 10:10:00 INFO - PROCESS | 2181 | Values: 149.0 113.0 114.0 148.0 115.0 114.0 116.0 116.0 144.0 113.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.00 stddev:1.37 (0.7%) stddev-sans-first:1.27 10:10:00 INFO - PROCESS | 2181 | Values: 183.0 185.0 185.0 185.0 185.0 184.0 184.0 184.0 186.0 188.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#3] audio-fft Cycles:10 Average:67.90 Median:62.00 stddev:12.74 (20.5%) stddev-sans-first:12.29 10:10:00 INFO - PROCESS | 2181 | Values: 83.0 60.0 59.0 91.0 64.0 60.0 60.0 59.0 84.0 59.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.00 stddev:11.43 (14.8%) stddev-sans-first:11.87 10:10:00 INFO - PROCESS | 2181 | Values: 84.0 70.0 95.0 70.0 68.0 68.0 89.0 68.0 69.0 93.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:87.00 stddev:11.55 (13.3%) stddev-sans-first:12.17 10:10:00 INFO - PROCESS | 2181 | Values: 88.0 85.0 109.0 117.0 89.0 86.0 88.0 84.0 88.0 83.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#6] imaging-darkroom Cycles:10 Average:96.60 Median:96.00 stddev:1.71 (1.8%) stddev-sans-first:1.80 10:10:00 INFO - PROCESS | 2181 | Values: 96.0 97.0 96.0 97.0 96.0 97.0 95.0 101.0 95.0 96.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#7] imaging-desaturate Cycles:10 Average:93.60 Median:81.50 stddev:19.65 (24.1%) stddev-sans-first:20.31 10:10:00 INFO - PROCESS | 2181 | Values: 81.0 101.0 126.0 132.0 85.0 80.0 85.0 83.0 82.0 81.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#8] json-parse-financial Cycles:10 Average:56.60 Median:57.00 stddev:1.84 (3.2%) stddev-sans-first:1.94 10:10:00 INFO - PROCESS | 2181 | Values: 57.0 57.0 53.0 54.0 59.0 58.0 58.0 56.0 57.0 57.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:2.88 (4.1%) stddev-sans-first:1.66 10:10:00 INFO - PROCESS | 2181 | Values: 64.0 73.0 73.0 75.0 71.0 70.0 70.0 71.0 71.0 71.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:84.50 stddev:2.41 (2.8%) stddev-sans-first:2.15 10:10:00 INFO - PROCESS | 2181 | Values: 88.0 83.0 85.0 82.0 83.0 88.0 84.0 83.0 81.0 86.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:151.50 stddev:23.33 (15.4%) stddev-sans-first:23.92 10:10:00 INFO - PROCESS | 2181 | Values: 141.0 156.0 151.0 148.0 145.0 147.0 145.0 152.0 218.0 177.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:169.30 Median:169.00 stddev:5.91 (3.5%) stddev-sans-first:6.26 10:10:00 INFO - PROCESS | 2181 | Values: 169.0 170.0 168.0 169.0 163.0 184.0 173.0 165.0 167.0 165.0 10:10:00 INFO - PROCESS | 2181 | 10:10:00 INFO - PROCESS | 2181 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.90 Median:76.50 stddev:10.25 (13.4%) stddev-sans-first:10.56 10:10:00 INFO - PROCESS | 2181 | Values: 67.0 68.0 63.0 62.0 69.0 84.0 86.0 85.0 87.0 68.0 10:10:00 INFO - PROCESS | 2181 | -------- Summary: end -------- 10:10:00 INFO - PROCESS | 2181 | 10:10:01 INFO - TEST-INFO | 2181: exit 0 10:10:01 INFO - TEST-OK | kraken | took 59548ms 10:10:01 INFO - SUITE-END | took 1178s 10:10:01 INFO - Completed test suite (00:19:38) 10:10:01 INFO - javascript benchmark 10:10:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3264.918377040574, 3265.5591495148165, 3276.8853090141843, 3281.1961069537965, 3291.1772056985756, 5554.750036248369, 5581.441855814416, 5612.494875461208, 5614.410486898873, 5633.0, 5540.3628582713, 5552.77788888444, 5556.138798486241, 5579.721013949296, 5601.0, 5051.0, 5059.620528460362, 5086.491350864902, 5164.096283150452, 5179.2749015137915, 3973.3046716824583, 4127.318992366256, 5077.314562534048, 5085.644004919657, 5128.743562821844, 5080.2125670520945, 5152.098382783017, 5152.407473140581, 5155.20094385369, 5168.948310516885, 5149.995750828609, 5164.147915593922, 5209.791608335662, 5237.502437268454, 5281.445448227919, 4932.679375840559, 5145.485451454862, 5154.896902061938, 5168.896622067575, 5174.120399532088, 5474.7810087596445, 5486.506214440679, 5495.725213739337, 5537.390887002426, 5546.389897111315, 6039.245094363205, 6058.3032951210735, 6062.848428789272, 6131.294901086343, 6132.202813634243, 6154.72303746328, 6217.906731399033, 6220.03589443637, 6237.875242495124, 6238.189035425411, 5582.413846546095, 5618.101103823368, 5628.943710562924, 5638.774449022075, 5691.857703557362, 5465.808696695596, 6100.0, 6240.781572644935, 6289.213848268966, 6301.180846490019, 6025.0661147522205, 6050.485708714808, 6065.727042283108, 6068.423499767515, 6080.543959203077, 5980.940190598125, 6003.609765365237, 6051.0923361496125, 6109.5112391008615, 6194.628322300677, 6027.45752882234, 6035.185249991283, 6043.909341359795, 6047.244094488189, 6056.394360563909, 6091.299500557493, 6125.938740612626, 6130.1038476501635, 6139.829471381233, 6185.721642526097, 4871.3667223260745, 4880.853574392774, 4882.707037577757, 4900.4854490278, 4901.656884018084, 4378.299472084451, 4392.626626736699, 4398.670099742532, 4409.647228221735, 4426.18115648606, 5525.171224316305, 5594.720263986866, 5612.214289999403, 5626.043572592669, 5659.490645841894, 6163.075538669146, 6210.472109870711, 6225.875482490324, 6226.159468471789, 6232.906506402408, 5674.06377947634, 5725.484706376446, 5727.169560413717, 5738.655680659173, 5762.366139724627, 4450.421445212167, 5199.298094757235, 5338.786448542015, 5339.679619222859, 5341.038613049688, 6220.315765265726, 6253.593516421418, 6265.154204182377, 6268.623882567061, 6275.02737075754, 6128.53367901495, 6202.596831205957, 6226.844328891814, 6227.003679411454, 6233.688315584112, 6290.9056364155485, 6293.748250069946, 6300.306966233802, 6321.841953951188, 6324.2410910691015, 5880.088586269135, 5938.762449501972, 6102.450779429695, 6105.755769769159, 6124.54065945047, 6107.795737660903, 6110.052941794029, 6113.083037544404, 6137.263528376623, 6140.631562106288, 5871.6183448075735, 5874.17761513397, 5875.6474611523445, 5877.735501902339, 5893.351731309638, 5652.491275785199, 5861.794837180678, 5879.382664820303, 5885.14665373527, 5897.970510147422, 5734.197212390352, 5744.965906136768, 5748.712564371681, 5756.481916627524, 5779.335376431764, 5762.971185144047, 5893.734781934738, 5921.2006379138265, 5921.526277897844, 5924.52603791687, 5717.0, 5745.195672605755, 5777.364489906023, 5777.971110144423, 5782.017057100387], "value": 5578.650484348666, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [452.6039715249156, 457.3688310132017, 461.8406649705851, 502.19899260679205, 554.2296208270504, 38980.805095974516, 40322.991925201844, 40417.59582404179, 42497.78751106248, 42516.36225456621, 41628.58371416292, 42026.36960445588, 42050.0, 42132.789336053276, 42170.156596868044, 8560.529170895597, 9333.53332333384, 9509.667161649344, 9522.238220942343, 9530.094641009127, 8710.782230444258, 11417.94291028548, 11686.707832304219, 11693.707657308592, 11877.465514051844, 11469.655910322663, 11512.539498420054, 11516.251443656136, 11563.479643416067, 11589.536418543248, 8682.913170868274, 8901.73294801157, 11404.71488212793, 11518.827217591785, 11519.42402879861, 8716.302695784321, 8777.561121943878, 8778.034416214212, 11149.498272577754, 11459.656210313704, 39245.80377098125, 39406.211875762325, 39557.80221098905, 39565.60434395648, 39668.801655991825, 3453.4647129694936, 3503.8598456061727, 3644.6173151818944, 3826.0626146593986, 3907.609239076098, 28702.282442938886, 29805.10584682463, 30170.245743856358, 30472.63930263721, 30641.387172256655, 3167.8099314041, 3191.776575639706, 3229.951550726741, 3260.152360386293, 3273.623533293654, 486936.0, 487050.0, 487202.0, 488162.55918720533, 494050.0, 58981.11528327079, 59108.0, 59126.0, 59200.7039964806, 59236.40763592395, 62282.688586556775, 62300.0, 62380.376196237456, 62542.37457625366, 62660.0, 15518.379264829506, 16097.0, 16124.919375403048, 16146.273417696231, 16153.838461615469, 11249.268797528135, 11523.942380288045, 11532.59635912739, 12528.997680185563, 12594.748105037848, 15039.548813535612, 15152.242387880562, 15173.544793656209, 16659.00045997244, 16960.982341059575, 5884.676342801187, 6029.336772954972, 6030.095485677183, 6081.878362432726, 6083.087536869417, 858.5406807358033, 880.9207171354481, 926.0831776541175, 1015.2639336480992, 1016.9880968436453, 28277.43445131086, 28815.99144029991, 29184.0, 29503.409931801245, 29529.85235073811, 868.4658934755206, 1024.8155332040305, 1042.2547878267076, 1051.6161601015574, 1053.7997780421695, 15397.615059623375, 15504.0, 15523.379064837505, 15686.372545098297, 15788.368465261261, 611504.0, 643364.0, 644837.0, 645861.0, 647532.0, 56773.71613141908, 56921.715391422775, 56960.43039569634, 56971.57571060757, 57080.85838283211, 1360.1702961193664, 1368.7399394115087, 1382.3433868912186, 1385.819843420349, 1389.388668985643, 28526.714732852404, 28818.423631527672, 28982.27544311325, 29685.554716678835, 29721.9194656372, 41794.79102604467, 50065.49934500754, 50469.74765126151, 50626.49373506218, 50638.746806265735, 462304.0, 463034.0, 464290.0, 464367.0, 465568.0, 39730.205395892495, 40385.798071009456, 40652.59347406488, 40686.0, 40891.0, 45052.32421513746, 45617.54382456133, 45666.0, 46258.8435289107, 46702.299465508724, 37240.813795930844, 37435.6256437443, 37507.24985500329, 37555.81222093981, 37772.43341349937, 36278.58731536043, 37520.437193441576, 38585.2282954345, 38910.02724931899, 39038.219235615696, 36764.26471470609, 36808.89573312805, 36956.81521592375, 37077.073073173386, 37204.255914882095, 31496.212594685316, 33853.492197616564, 33933.8123165688, 34149.82925085458, 34306.65693343035, 24625.384365391008, 25077.74922250754, 25288.873555632104, 25682.0, 25691.100811471508], "value": 19870.48828930531, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [350.7106637024456, 368.285526079406, 376.1499012232354, 384.4137690022716, 401.945737325461, 14593.854061459382, 14782.55652330429, 15921.442749503783, 20236.291729789424, 20460.07929643166, 18999.62000759984, 19438.319658811943, 19946.90026549865, 20154.596908061867, 20243.49391265219, 5750.712464376786, 5985.431384018511, 6051.6671583063135, 6058.969975104243, 6074.757009719606, 5726.141078838166, 5956.851078723045, 6015.939840601582, 6020.5550667839625, 6020.698965051752, 5679.432056794308, 5927.525797936155, 5946.22699049123, 5949.077892926603, 5951.732172052268, 5911.3201981772, 6104.267487901436, 6105.481034112084, 6106.175666285084, 6109.755609775603, 5667.376588575254, 5803.484681650346, 5867.11993201019, 5913.615614985012, 5929.288485381739, 10733.516991735312, 13446.260455674934, 13480.5281815136, 14645.707085858223, 25325.493490130277, 2059.5571952030236, 2250.144944920936, 2344.6717459555507, 2517.546841568517, 2578.651881995944, 9932.155766759834, 10227.642032528825, 10253.025962533547, 12531.686707832287, 14622.926885365612, 1849.5006348285888, 1855.452641470756, 1893.8579606529427, 1904.2002359009111, 1938.0890981238795, 113857.0, 117427.0, 118407.81592184053, 120169.79830201673, 124194.0, 11225.943870280596, 11393.829092563617, 11415.714607134722, 11473.196876218752, 11632.418379081013, 14495.130292182503, 14498.492552760603, 14503.709925801424, 14532.27338633064, 14540.636484087983, 8046.959765201136, 8298.875516867252, 8367.456115352483, 8468.280196183394, 8471.745847624581, 7918.366530677532, 8229.917700823034, 8248.752537423767, 8402.201790829862, 8415.915840841513, 3907.296686596383, 4333.848315308949, 4460.821567137278, 4559.772011399483, 4560.703554269028, 1776.8578513718871, 1827.643609496162, 1828.067685480388, 1835.7328699056654, 1837.6784062788959, 850.46420754924, 901.2114399900088, 901.8917729872458, 908.2869947091633, 963.7012526116927, 19476.70784938227, 20692.275770348268, 20891.58216835685, 21100.683489747666, 21220.469488262916, 914.9679761208325, 949.3070058856935, 956.4118067388506, 971.6259240192577, 980.6273616025865, 10507.47462626881, 11255.380954047443, 11263.549458021751, 11299.54801807935, 11303.830442543369, 593769.0, 601686.0, 608851.0, 620113.0, 622367.0, 85853.0, 94682.0, 98475.0, 104163.0, 122899.0, 887.9289656827439, 944.0889541592534, 994.8955359687128, 996.342414006752, 996.7308826616918, 18633.440996770118, 18886.905565472083, 19290.4212873614, 19358.128884199963, 19427.8057219426, 44603.77698111489, 55480.6129846757, 55811.0, 56679.71660141673, 56978.43021569731, 58978.70510647419, 72696.6365168192, 73767.0, 76191.8571221421, 77846.6107669458, 10715.142788577052, 12093.516259349528, 12129.272243665408, 12181.512739490321, 12592.055595830167, 13188.80216796768, 13535.458581656623, 13573.253471059186, 13964.720705586034, 14025.929870350583, 10915.563377464812, 11541.538338466704, 11651.650450486499, 11789.941050294694, 11957.342346171044, 11275.0, 11455.484503197542, 11661.825072623747, 11835.11236657237, 11988.76022479528, 11677.474513647076, 11725.589604363806, 11899.702507437383, 11920.344381059125, 12204.948762809297, 9116.589753461243, 11189.160812939224, 12066.879331206575, 12360.0, 12385.256884586954, 9687.034277198212, 11580.305181689126, 11613.825792612948, 11813.054955603358, 12063.276203427822], "value": 9613.28048527462, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.1490535489413, 1485.1163557683178, 1654.3051918194358, 1807.2319264312664, 1825.0418530271613, 8213.712520061792, 8626.439281446717, 8696.04343522212, 8709.21617054465, 8711.69509067182, 7565.394768418527, 7859.410544209179, 7860.92139078609, 7957.641906114224, 7965.641546130423, 1889.7165425186263, 2116.566103948691, 2376.584097782881, 2380.6191009438494, 2400.423898264413, 2155.8059774620283, 2297.597920363938, 2388.6894703688495, 2405.3625789165885, 2410.276916924924, 2369.6327069304198, 2392.055138220402, 2393.640953856918, 2397.5564520563753, 2403.735589085199, 2340.5201933603657, 2386.522695460906, 2390.151496218835, 2392.892319845611, 2399.784019438259, 2395.6646069550275, 2397.7602239776056, 2405.494846082325, 2416.830821842454, 2417.6615273861676, 8445.619947102396, 8622.267107295887, 8631.827363452758, 8640.956795215983, 8641.395102342838, 5172.560332371753, 5344.465553444663, 5348.652337598043, 5352.892942141174, 5362.865928351784, 3405.2338223899674, 3611.6388361163936, 3671.70626349894, 3676.4669122977284, 3679.208970071421, 1025.4155131575005, 1059.7774467361862, 1104.536094840169, 1110.9222354435192, 1114.4706264524368, 10769.0, 11114.55541778336, 11269.887301127048, 11280.984711376015, 11416.31502109876, 4429.623482004065, 4515.593596576258, 4539.387182730356, 4552.271636538138, 4566.269396896547, 7903.525788452711, 8799.076097009722, 8889.599968001456, 8958.910410895938, 8961.507117108622, 5181.145111056709, 5182.196759502283, 5190.091733946544, 5190.117679994334, 5196.662216955886, 4383.342498625232, 4712.64655150865, 4861.6353773467135, 4895.265710143507, 4923.926141107886, 3460.1003739028033, 3568.0901370150445, 3648.4892115103908, 3793.81030948457, 3794.905127371783, 1141.937193454368, 1173.724174818917, 1174.8237764335418, 1180.9704757381135, 1184.1178322150054, 866.6013633728555, 887.5843030366555, 899.6176624934508, 901.0043901488891, 903.6656437118309, 5535.723213839292, 5715.457031581993, 5745.597808153473, 5815.651060936358, 5967.910481342783, 895.8611415230649, 902.264654306738, 906.2297047509696, 912.6805618033635, 954.8854137503544, 3328.4508056170944, 3737.4767532545466, 3745.3445647011663, 3843.6540711335556, 3978.508059477696, 10018.64934727281, 10284.794304114026, 10419.583216671399, 10427.530761115768, 10459.215558833119, 10381.688549343531, 10382.844257336146, 10383.9480802597, 10389.584416623402, 10394.428306443067, 7850.2934735872905, 7988.201179882081, 8039.758807235792, 8048.637811298506, 8061.355091592659, 5482.506574408322, 5668.914966275592, 5680.573956953328, 5849.502792262535, 5959.195508606283, 8255.298299644357, 9494.0, 9509.0, 9610.087041731025, 9660.985596512264, 9380.48407337603, 9715.125638692552, 9859.0, 9861.753456163653, 9865.704028879145, 7262.891056634049, 7399.8150046249275, 7404.592747398729, 7406.851862962818, 7410.592417417094, 7270.709171633075, 7331.120265567953, 7588.734394296394, 7598.96200519016, 8069.556174410463, 6967.163940327193, 7245.203027666847, 7284.927150728637, 7313.085864266966, 7417.0, 6472.093906852951, 6495.447886929475, 6864.931350686429, 7096.929030709627, 7180.5691658500655, 6216.53375996793, 6772.356626120511, 6803.25164231944, 6854.451643868579, 6901.861962760817, 4811.663183577116, 4838.758062096811, 4873.171560834596, 4911.582515486224, 5064.139096353555, 4931.3835770528685, 4948.331975183448, 5007.874803129951, 5018.272350509229, 5058.974705126598], "value": 4457.083959115598, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.63022482346713, 307.9399517094166, 310.6272473032361, 315.34565776014773, 316.9683031696832, 4276.572342765726, 4318.8488402905905, 4938.728369939652, 5137.152369858978, 5206.277511579225, 4240.512341080776, 4910.772306923269, 5129.589632829374, 5184.326037615105, 5203.271541984116, 3297.389982853168, 3675.9891029966707, 3785.186184970231, 3855.614438556136, 3901.629345212207, 3929.764214147146, 3947.8618248361313, 3971.523417189941, 3979.4929194607357, 3987.7806720630356, 3284.868605255787, 3938.8030598470104, 3944.23087497938, 3948.9012774680577, 3983.4423180754716, 3540.91147721309, 3645.9453108203265, 3920.941185882214, 3931.528216613996, 3953.3081710700512, 3434.553508043963, 3442.6557344265375, 3457.5850897892165, 3459.550258466383, 3476.7392445566684, 4936.901261974776, 4946.5795407390415, 4957.851264462072, 4965.950340496585, 4978.576820970222, 3586.6234045425376, 3745.887623371303, 4287.892802679927, 4446.822127114911, 4453.481749417956, 4520.163769702615, 4667.369905062841, 4784.617230621542, 4917.262410638397, 5341.065313570149, 3345.7323414126813, 3708.5364329458816, 3763.360228761117, 3764.0589852536864, 3769.264993326275, 4446.777661116931, 5022.8196373852115, 5678.7790625015405, 5757.568182386296, 5773.566982476289, 3676.5771936227143, 3737.9439308410397, 3744.8314825832904, 3753.399456087013, 3759.0414444316525, 3587.9727953706074, 3620.9818950905606, 3760.830762615689, 3793.715471339661, 3832.4549791442805, 3728.5339332583426, 4433.866983990485, 4458.9298568343365, 4463.866084017484, 4504.25679762842, 4488.573585509436, 4983.725895075806, 5410.431904650034, 5424.213489044067, 5526.281583394213, 1151.1826603111883, 1198.9760204795855, 1240.2124650846777, 1242.7079636285466, 1243.533674871923, 718.8346680263569, 781.9335356494762, 839.8152406470567, 852.7081471570575, 854.4531499840107, 4413.360062790942, 4715.363425937523, 4933.827316043993, 5037.924431133536, 5079.771410286546, 4924.556789888928, 5558.6942718150085, 5581.665100094007, 5598.7962588043765, 5700.885982280414, 4360.847370342086, 4521.412216411911, 4761.809587576332, 4842.0, 5204.219367094966, 2497.600383938561, 2497.9125730599344, 2641.0492222799776, 2719.265798234466, 2810.737356428054, 4394.406755088086, 4705.341252224691, 4956.265307142471, 4970.453250142481, 5472.671639701631, 4605.263157894788, 5031.471695471996, 5280.468664087449, 5365.919511207335, 5480.437715484887, 4867.780949857265, 5036.597072234213, 5155.304033955518, 5284.916592098611, 5376.274202982626, 4603.930941035954, 5234.738263086754, 5268.892443022668, 5310.787568497217, 5438.918416223681, 4408.933865992077, 4478.395416618715, 4704.035672687022, 5066.721330326867, 5205.895882082262, 4852.296417019442, 5370.9590355664595, 5435.456454354533, 5440.891182176255, 5445.618806683494, 3217.9356412871143, 3222.6455089940555, 3403.5745531808525, 3568.803715795656, 3602.47764074213, 4405.709031303768, 4897.804087836446, 4974.477679843564, 5054.671446355975, 5256.316678831728, 4683.882902927454, 4839.733814640229, 4937.876553086202, 5110.795568177231, 5146.221040638163, 3984.980075099722, 4710.764461776966, 5144.202648589475, 5189.351331083614, 5225.973870130625, 4215.557366476476, 4598.149342371672, 4711.8771075309105, 4922.227997920817, 5031.20767973778, 4894.632902532396, 5265.710385928811, 5279.39286982002, 5295.920561191662, 5302.2576839241765, 4322.092360604308, 4653.604443622195, 4890.483949975519, 5155.839936014367, 5283.65656232359], "value": 3814.139450664198, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.390696187355, 706.2161001288572, 718.5185925429961, 747.5402627384157, 757.4508481351018, 13473.124246923955, 14083.0, 14118.717625647494, 14122.929385353085, 14131.364088616012, 12886.420111095, 13027.218366897992, 13040.80438793419, 13104.73790524189, 13110.147840390367, 633.1831936801532, 654.116942128128, 788.6451097006342, 812.695239285268, 823.6540652925786, 799.9320057795095, 810.0401024785618, 812.3622955979557, 816.0166998766501, 817.9141190175035, 863.4258218284833, 864.8789169516241, 865.4807115730574, 866.6576702202626, 867.3364875869964, 624.5035197018336, 632.171854870117, 667.8397184675691, 860.3633311349619, 862.659249596409, 510.1582389058047, 510.51246060012596, 617.5646169450526, 639.6226226526372, 642.2197030607787, 14117.929410352883, 14143.504977325847, 14152.504662336769, 14156.646083847883, 14158.858411415857, 12897.67755806103, 12994.28531430771, 13179.0, 13196.0, 13274.601761947157, 11789.233699809485, 12094.153409261355, 12119.0, 12180.51277948881, 12276.202046867014, 4211.831526738927, 4254.851080212208, 4289.892752681208, 4346.1090476452255, 4517.073999830027, 87228.0, 87730.0, 88124.55937720335, 88144.55927720385, 89057.55471222602, 9495.857562136573, 9561.760955976157, 10106.848397274047, 10119.342242754197, 10156.085952264331, 19263.51841203953, 19273.132709028127, 19793.20827166926, 19916.103775329855, 19926.103325350396, 7228.855422891513, 7323.853522929511, 7372.373348265458, 7555.24447555251, 7603.7338693145475, 4029.6373326400767, 4085.346344584852, 4165.854195103157, 4216.840368898516, 4275.251830929575, 3235.6602556731673, 3289.0, 3325.0523600774145, 3327.101682545699, 3337.9499307510405, 608.7777961044187, 622.9408206220402, 631.390707966805, 632.3486808587071, 639.3766078073841, 522.5062316111314, 606.3118360660594, 614.631221267245, 630.7035693224177, 637.2321352769901, 8216.876746848802, 8594.183552562497, 8693.782655433539, 8731.170538798804, 8873.157050080232, 600.6366148480193, 616.7563812294119, 622.8380621038563, 626.9404406581367, 645.7933461292436, 4105.609967053125, 4181.519125300569, 4189.141226048653, 4299.935500967488, 4371.584699453547, 114979.0, 115360.42319788515, 115596.42201788937, 117268.24097638542, 118273.40863295629, 77402.0, 91971.5401423002, 102909.0, 105041.94958050322, 107412.46293768482, 59412.10881836776, 59732.10401843976, 84006.0, 87162.56418717866, 89260.6610900824, 8036.0, 8076.27313541784, 8122.959385203272, 8125.065617454068, 8503.574821259035, 55828.720856395455, 57634.84730305454, 59357.70321148367, 60401.657825929404, 61839.0, 78014.0, 84048.57975710083, 88103.0, 88391.0, 88608.0, 10678.62624808128, 10701.51843167075, 10732.570697172338, 11032.834507482556, 11614.0, 10076.848847267443, 11355.318680879174, 11368.658940231806, 11557.82663260035, 12050.75898482043, 11220.831687524857, 11494.54021839117, 11588.072954163816, 11597.710057248636, 12041.976432003028, 10868.51091700891, 10941.781164376509, 11186.161037922026, 11426.0, 11926.224795388272, 11130.276532025393, 11250.66248012561, 11362.488688009225, 11390.25963312382, 11605.81628003739, 9385.530723463662, 9611.0, 9619.134277915022, 9679.0, 10090.19278457707, 8355.415120941476, 8606.78483037929, 8610.0, 8662.220400164015, 8743.868841967249], "value": 6972.616294405673, "unit": "score"}], "name": "dromaeo_css", "value": 7083.542571654256, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 113.0, 116.0, 114.0, 114.0, 114.0, 114.0, 115.0, 113.0, 113.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 113.0, 114.0, 148.0, 115.0, 114.0, 116.0, 116.0, 144.0, 113.0], "value": 124.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [183.0, 185.0, 185.0, 185.0, 185.0, 184.0, 184.0, 184.0, 186.0, 188.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 59.0, 91.0, 64.0, 60.0, 60.0, 59.0, 84.0, 59.0], "value": 67.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 95.0, 70.0, 68.0, 68.0, 89.0, 68.0, 69.0, 93.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 109.0, 117.0, 89.0, 86.0, 88.0, 84.0, 88.0, 83.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 96.0, 97.0, 96.0, 97.0, 95.0, 101.0, 95.0, 96.0], "value": 96.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 101.0, 126.0, 132.0, 85.0, 80.0, 85.0, 83.0, 82.0, 81.0], "value": 93.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 53.0, 54.0, 59.0, 58.0, 58.0, 56.0, 57.0, 57.0], "value": 56.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 73.0, 73.0, 75.0, 71.0, 70.0, 70.0, 71.0, 71.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 83.0, 85.0, 82.0, 83.0, 88.0, 84.0, 83.0, 81.0, 86.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 156.0, 151.0, 148.0, 145.0, 147.0, 145.0, 152.0, 218.0, 177.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 168.0, 169.0, 163.0, 184.0, 173.0, 165.0, 167.0, 165.0], "value": 169.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 63.0, 62.0, 69.0, 84.0, 86.0, 85.0, 87.0, 68.0], "value": 73.9, "unit": "score"}], "name": "kraken", "value": 1463.4, "alertThreshold": 2.0}]} 10:10:02 INFO - Return code: 0 10:10:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:10:02 ERROR - # TBPL SUCCESS # 10:10:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:10:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:10:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:10:02 INFO - 2016-11-17 10:10:02.095 screenresolution[2185:24661] starting screenresolution argv=screenresolution get 10:10:02 INFO - 2016-11-17 10:10:02.097 screenresolution[2185:24661] Display 0: 1600x1200x32@60 10:10:02 INFO - 2016-11-17 10:10:02.104 screenresolution[2186:24664] starting screenresolution argv=screenresolution list 10:10:02 INFO - Available Modes on Display 0 10:10:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:10:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:10:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:10:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:10:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:10:02 INFO - Intel Iris: 10:10:02 INFO - Chipset Model: Intel Iris 10:10:02 INFO - Type: GPU 10:10:02 INFO - Bus: Built-In 10:10:02 INFO - VRAM (Dynamic, Max): 1536 MB 10:10:02 INFO - Vendor: Intel (0x8086) 10:10:02 INFO - Device ID: 0x0a2e 10:10:02 INFO - Revision ID: 0x0009 10:10:02 INFO - Displays: 10:10:02 INFO - Display: 10:10:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:10:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:10:02 INFO - Display Serial Number: 1600x1200 60 10:10:02 INFO - Main Display: Yes 10:10:02 INFO - Mirror: Off 10:10:02 INFO - Online: Yes 10:10:02 INFO - Rotation: Supported 10:10:02 INFO - Return code: 0 10:10:02 INFO - Running post-action listener: _resource_record_post_action 10:10:02 INFO - [mozharness: 2016-11-17 18:10:02.338967Z] Finished run-tests step (success) 10:10:02 INFO - Running post-run listener: _resource_record_post_run 10:10:02 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 102400; Write bytes: 652595200; Read time: 2; Write time: 1884 10:10:02 INFO - TinderboxPrint: CPU usage
53.4% 10:10:02 INFO - TinderboxPrint: I/O read bytes / time
102,400 / 2 10:10:02 INFO - TinderboxPrint: I/O write bytes / time
652,595,200 / 1,884 10:10:02 INFO - TinderboxPrint: CPU idle
2,260.6 (46.6%) 10:10:02 INFO - TinderboxPrint: CPU system
967.3 (19.9%) 10:10:02 INFO - TinderboxPrint: CPU user
1,621.8 (33.4%) 10:10:02 INFO - TinderboxPrint: Swap in / out
352,583,680 / 0 10:10:02 INFO - install - Wall time: 33s; CPU: 55.0%; Read bytes: 344570368; Write bytes: 345737216; Read time: 27639; Write time: 696 10:10:02 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 102400; Write bytes: 296835072; Read time: 2; Write time: 1158 10:10:02 INFO - Running post-run listener: _upload_blobber_files 10:10:02 INFO - Blob upload gear active. 10:10:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:10:02 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:10:02 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'] 10:10:02 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 10:10:03 INFO - (blobuploader) - INFO - Open directory for files ... 10:10:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:10:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:10:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:10:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:10:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:10:03 INFO - (blobuploader) - INFO - Done attempting. 10:10:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:10:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:10:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:10:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:10:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:10:04 INFO - (blobuploader) - INFO - Done attempting. 10:10:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:10:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:10:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:10:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:10:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:10:07 INFO - (blobuploader) - INFO - Done attempting. 10:10:07 INFO - (blobuploader) - INFO - Iteration through files over. 10:10:07 INFO - Return code: 0 10:10:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:10:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:10:07 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee3b66d98704100e9444f784678f252895c358cb402c845ba17af13e7f5de42add9141372456a5dbf1428c6a28f7b79f014931490f3886ec285e3677ca517f35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383634ef680face5b3959cd0aa009fc61b025ad448ccf9506cc3198e6b4f02daf0abf32557a112cfbf862215b0d483e14fb633886330824b27d2490be9b29f69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fce52eabe104ecb8a53e16e57f1dee42904e29bf31e41866345189a7cba80dff8dc728d93be86fdb86afdc5b9ae8a41b92c10d0a677e9ad55c7bddd25633bac"} 10:10:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:10:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:10:07 INFO - Contents: 10:10:07 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee3b66d98704100e9444f784678f252895c358cb402c845ba17af13e7f5de42add9141372456a5dbf1428c6a28f7b79f014931490f3886ec285e3677ca517f35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383634ef680face5b3959cd0aa009fc61b025ad448ccf9506cc3198e6b4f02daf0abf32557a112cfbf862215b0d483e14fb633886330824b27d2490be9b29f69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fce52eabe104ecb8a53e16e57f1dee42904e29bf31e41866345189a7cba80dff8dc728d93be86fdb86afdc5b9ae8a41b92c10d0a677e9ad55c7bddd25633bac"} 10:10:07 INFO - Running post-run listener: copy_logs_to_upload_dir 10:10:07 INFO - Copying logs to upload dir... 10:10:07 INFO - mkdir: /builds/slave/test/build/upload/logs 10:10:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.129812 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-11-17 10:10:07.201461) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 10:10:07.205304) ========= 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.u5y4IdXHyj/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.72g48FAOQc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee3b66d98704100e9444f784678f252895c358cb402c845ba17af13e7f5de42add9141372456a5dbf1428c6a28f7b79f014931490f3886ec285e3677ca517f35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383634ef680face5b3959cd0aa009fc61b025ad448ccf9506cc3198e6b4f02daf0abf32557a112cfbf862215b0d483e14fb633886330824b27d2490be9b29f69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fce52eabe104ecb8a53e16e57f1dee42904e29bf31e41866345189a7cba80dff8dc728d93be86fdb86afdc5b9ae8a41b92c10d0a677e9ad55c7bddd25633bac"} build_url:https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011084 build_url: 'https://queue.taskcluster.net/v1/task/VL7YjAIOT3un8Cf23IFaEA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/ee3b66d98704100e9444f784678f252895c358cb402c845ba17af13e7f5de42add9141372456a5dbf1428c6a28f7b79f014931490f3886ec285e3677ca517f35", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/383634ef680face5b3959cd0aa009fc61b025ad448ccf9506cc3198e6b4f02daf0abf32557a112cfbf862215b0d483e14fb633886330824b27d2490be9b29f69", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5fce52eabe104ecb8a53e16e57f1dee42904e29bf31e41866345189a7cba80dff8dc728d93be86fdb86afdc5b9ae8a41b92c10d0a677e9ad55c7bddd25633bac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-17 10:10:07.238173) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:10:07.238518) ========= 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.u5y4IdXHyj/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.72g48FAOQc/Listeners TMPDIR=/var/folders/b5/152b2m257t95w2vfwktssysw00000w/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.005256 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-17 10:10:07.287629) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 10:10:07.287962) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-17 10:10:07.288312) ========= ========= Total master_lag: 0.18 =========