builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0127 starttime: 1476352606.78 results: success (0) buildid: 20161013004823 builduid: 2faf394485de49c1bd9e8092a2e89715 revision: 7480f3d08b18ee0d347a944020509c2d57bb5cf0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:46.783230) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:46.784054) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:46.784350) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:46.847425) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:46.847797) ========= 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.iQb96mJcDU/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.0g9cq1dyNU/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-10-13 02:56:46-- 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% 53.5M=0s 2016-10-13 02:56:47 (53.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.499318 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:47.369279) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:47.369552) ========= 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.iQb96mJcDU/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.0g9cq1dyNU/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.033259 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:47.425468) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 02:56:47.428977) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 7480f3d08b18ee0d347a944020509c2d57bb5cf0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 7480f3d08b18ee0d347a944020509c2d57bb5cf0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iQb96mJcDU/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.0g9cq1dyNU/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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-10-13 02:56:47,509 truncating revision to first 12 chars 2016-10-13 02:56:47,509 Setting DEBUG logging. 2016-10-13 02:56:47,509 attempt 1/10 2016-10-13 02:56:47,509 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/7480f3d08b18?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 02:56:48,244 unpacking tar archive at: autoland-7480f3d08b18/testing/mozharness/ program finished with exit code 0 elapsedTime=0.983281 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 02:56:48.439067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:48.439656) ========= script_repo_revision: 7480f3d08b18ee0d347a944020509c2d57bb5cf0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:48.440318) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:48.440788) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 02:56:48.460851) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-13 02:56:48.461455) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iQb96mJcDU/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.0g9cq1dyNU/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:56:48 INFO - MultiFileLogger online at 20161013 02:56:48 in /builds/slave/test 02:56:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 02:56:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:56:48 INFO - {'append_to_log': False, 02:56:48 INFO - 'base_work_dir': '/builds/slave/test', 02:56:48 INFO - 'blob_upload_branch': 'Autoland', 02:56:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:56:48 INFO - 'branch': 'Autoland', 02:56:48 INFO - 'buildbot_json_path': 'buildprops.json', 02:56:48 INFO - 'config_files': ('talos/mac_config.py',), 02:56:48 INFO - 'default_actions': ('clobber', 02:56:48 INFO - 'read-buildbot-config', 02:56:48 INFO - 'download-and-extract', 02:56:48 INFO - 'populate-webroot', 02:56:48 INFO - 'create-virtualenv', 02:56:48 INFO - 'install', 02:56:48 INFO - 'run-tests'), 02:56:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:56:48 INFO - 'download_minidump_stackwalk': True, 02:56:48 INFO - 'download_symbols': 'ondemand', 02:56:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:56:48 INFO - 'tooltool.py': '/tools/tooltool.py', 02:56:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:56:48 INFO - '/tools/misc-python/virtualenv.py')}, 02:56:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:56:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:56:48 INFO - 'installer_path': 'installer.exe', 02:56:48 INFO - 'log_level': 'info', 02:56:48 INFO - 'log_name': 'talos', 02:56:48 INFO - 'log_to_console': True, 02:56:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:56:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:56:48 INFO - 'opt_config_files': (), 02:56:48 INFO - 'pip_index': False, 02:56:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:48 INFO - 'cmd': ('bash', 02:56:48 INFO - '-c', 02:56:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:48 INFO - 'enabled': True, 02:56:48 INFO - 'halt_on_failure': False, 02:56:48 INFO - 'name': 'check_screen_resolution'},), 02:56:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:56:48 INFO - 'cmd': ('bash', 02:56:48 INFO - '-c', 02:56:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:56:48 INFO - 'enabled': True, 02:56:48 INFO - 'halt_on_failure': False, 02:56:48 INFO - 'name': 'check_screen_resolution'},), 02:56:48 INFO - 'run_cmd_checks_enabled': True, 02:56:48 INFO - 'sps_profile': False, 02:56:48 INFO - 'sps_profile_interval': 0, 02:56:48 INFO - 'suite': 'dromaeojs', 02:56:48 INFO - 'system_bits': '32', 02:56:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:56:48 INFO - 'title': 't-yosemite-r7-0127', 02:56:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:56:48 INFO - 'use_talos_json': True, 02:56:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:56:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:56:48 INFO - 'work_dir': 'build'} 02:56:48 INFO - [mozharness: 2016-10-13 09:56:48.670007Z] Running clobber step. 02:56:48 INFO - Running pre-action listener: _resource_record_pre_action 02:56:48 INFO - Running main action method: clobber 02:56:48 INFO - rmtree: /builds/slave/test/build 02:56:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:56:50 INFO - Running post-action listener: _resource_record_post_action 02:56:50 INFO - [mozharness: 2016-10-13 09:56:50.879122Z] Finished clobber step (success) 02:56:50 INFO - [mozharness: 2016-10-13 09:56:50.879254Z] Running read-buildbot-config step. 02:56:50 INFO - Running pre-action listener: _resource_record_pre_action 02:56:50 INFO - Running main action method: read_buildbot_config 02:56:50 INFO - Using buildbot properties: 02:56:50 INFO - { 02:56:50 INFO - "project": "", 02:56:50 INFO - "product": "firefox", 02:56:50 INFO - "script_repo_revision": "production", 02:56:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 02:56:50 INFO - "repository": "", 02:56:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 02:56:50 INFO - "buildid": "20161013004823", 02:56:50 INFO - "pgo_build": "False", 02:56:50 INFO - "basedir": "/builds/slave/test", 02:56:50 INFO - "buildnumber": 260, 02:56:50 INFO - "slavename": "t-yosemite-r7-0127", 02:56:50 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:56:50 INFO - "platform": "yosemite_r7", 02:56:50 INFO - "scheduler": "tests-autoland-macosx64-talos", 02:56:50 INFO - "branch": "autoland", 02:56:50 INFO - "repo_path": "integration/autoland", 02:56:50 INFO - "revision": "7480f3d08b18ee0d347a944020509c2d57bb5cf0", 02:56:50 INFO - "stage_platform": "macosx64", 02:56:50 INFO - "builduid": "2faf394485de49c1bd9e8092a2e89715", 02:56:50 INFO - "slavebuilddir": "test" 02:56:50 INFO - } 02:56:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 02:56:50 INFO - Running post-action listener: _resource_record_post_action 02:56:50 INFO - [mozharness: 2016-10-13 09:56:50.882548Z] Finished read-buildbot-config step (success) 02:56:50 INFO - [mozharness: 2016-10-13 09:56:50.882667Z] Running download-and-extract step. 02:56:50 INFO - Running pre-action listener: _resource_record_pre_action 02:56:50 INFO - Running main action method: download_and_extract 02:56:50 INFO - mkdir: /builds/slave/test/build/tests 02:56:50 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')]} 02:56:50 INFO - trying https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 02:56:50 INFO - Downloading https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:56:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 02:56:52 INFO - Downloaded 1199 bytes. 02:56:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 02:56:52 INFO - Using the following test package requirements: 02:56:52 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:56:52 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 02:56:52 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 02:56:52 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'jsshell-mac.zip'], 02:56:52 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 02:56:52 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 02:56:52 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 02:56:52 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 02:56:52 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 02:56:52 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 02:56:52 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 02:56:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:56:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:56:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:56:52 INFO - Fetch https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:56:52 INFO - Http code: 200 02:56:52 INFO - via: 1.1 336f0e6ef9a3462f682d6ca49029b665.cloudfront.net (CloudFront) 02:56:52 INFO - x-amz-cf-id: ZwcSPPbKa0_fxTDtcmLCibsx4J1_f75_J_0iqm4bGthLWZ1-MAqEfg== 02:56:52 INFO - x-amz-version-id: VB73iqkcpE.M6iDTn2IKKRDGNZ_yW23g 02:56:53 INFO - Expected file size: 16511012 02:56:53 INFO - Obtained file size: 16511012 02:56:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:56:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 02:56:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:56:59 INFO - Fetch https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 02:57:00 INFO - Http code: 200 02:57:00 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 02:57:00 INFO - x-amz-cf-id: 0op2abwyIz--gF_mkZyjS-nN1HOQMShrF2kt0zEAEekANzkJ9yyAYA== 02:57:00 INFO - x-amz-version-id: VB73iqkcpE.M6iDTn2IKKRDGNZ_yW23g 02:57:00 INFO - Expected file size: 16511012 02:57:00 INFO - Obtained file size: 16511012 02:57:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 02:57:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:57:07 INFO - Fetch https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 02:57:07 INFO - Http code: 200 02:57:07 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 02:57:07 INFO - x-amz-cf-id: msx7e_4eZCnYz_il9KFx7NNGm2-eJLqRt27mSamjJW6eXesEEs-BJg== 02:57:07 INFO - x-amz-version-id: 65kZ6A7ydNPVxF9iu6b6kHJsbLQODnNo 02:57:08 INFO - Expected file size: 14409006 02:57:08 INFO - Obtained file size: 14409006 02:57:08 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')]} 02:57:08 INFO - trying https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:57:08 INFO - Downloading https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 02:57:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 02:57:11 INFO - Downloaded 111669714 bytes. 02:57:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:57:11 INFO - mkdir: /builds/slave/test/properties 02:57:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:57:11 INFO - Writing to file /builds/slave/test/properties/build_url 02:57:11 INFO - Contents: 02:57:11 INFO - build_url:https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 02:57:12 INFO - Running post-action listener: _resource_record_post_action 02:57:12 INFO - Running post-action listener: set_extra_try_arguments 02:57:12 INFO - [mozharness: 2016-10-13 09:57:12.665774Z] Finished download-and-extract step (success) 02:57:12 INFO - [mozharness: 2016-10-13 09:57:12.665905Z] Running populate-webroot step. 02:57:12 INFO - Running pre-action listener: _resource_record_pre_action 02:57:12 INFO - Running main action method: populate_webroot 02:57:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:57:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:57:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:57:12 INFO - '--tppagecycles', 02:57:12 INFO - '7'], 02:57:12 INFO - 'tests': ['tsvgm']}}, 02:57:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:57:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:57:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:57:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:57:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:57:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['damp', 'tps']}, 02:57:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'tests': ['damp', 'tps']}, 02:57:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['dromaeo_dom']}, 02:57:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:57:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['basic_compositor_video']}, 02:57:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:57:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['a11yr', 02:57:12 INFO - 'ts_paint', 02:57:12 INFO - 'tpaint', 02:57:12 INFO - 'sessionrestore', 02:57:12 INFO - 'sessionrestore_no_auto_restore', 02:57:12 INFO - 'tabpaint']}, 02:57:12 INFO - 'other-e10s': {'tests': ['a11yr', 02:57:12 INFO - 'ts_paint', 02:57:12 INFO - 'tpaint', 02:57:12 INFO - 'sessionrestore', 02:57:12 INFO - 'sessionrestore_no_auto_restore', 02:57:12 INFO - 'tabpaint']}, 02:57:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:57:12 INFO - 'ts_paint', 02:57:12 INFO - 'tpaint', 02:57:12 INFO - 'sessionrestore', 02:57:12 INFO - 'sessionrestore_no_auto_restore', 02:57:12 INFO - 'tabpaint']}, 02:57:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:57:12 INFO - 'ts_paint', 02:57:12 INFO - 'tpaint', 02:57:12 INFO - 'sessionrestore', 02:57:12 INFO - 'sessionrestore_no_auto_restore', 02:57:12 INFO - 'tabpaint']}, 02:57:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['a11yr', 02:57:12 INFO - 'ts_paint', 02:57:12 INFO - 'tpaint', 02:57:12 INFO - 'sessionrestore', 02:57:12 INFO - 'sessionrestore_no_auto_restore', 02:57:12 INFO - 'tabpaint']}, 02:57:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['a11yr', 02:57:12 INFO - 'ts_paint', 02:57:12 INFO - 'tpaint', 02:57:12 INFO - 'sessionrestore', 02:57:12 INFO - 'sessionrestore_no_auto_restore', 02:57:12 INFO - 'tabpaint']}, 02:57:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['tsvgx', 02:57:12 INFO - 'tsvgr_opacity', 02:57:12 INFO - 'tart', 02:57:12 INFO - 'tscrollx', 02:57:12 INFO - 'cart']}, 02:57:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:57:12 INFO - 'tsvgr_opacity', 02:57:12 INFO - 'tart', 02:57:12 INFO - 'tscrollx', 02:57:12 INFO - 'cart']}, 02:57:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'talos_options': ['--disable-e10s'], 02:57:12 INFO - 'tests': ['tp5o']}, 02:57:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'tests': ['tp5o']}, 02:57:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'talos_options': ['--disable-e10s', 02:57:12 INFO - '--xperf_path', 02:57:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:12 INFO - 'tests': ['tp5n']}, 02:57:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:57:12 INFO - 'talos_options': ['--xperf_path', 02:57:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:57:12 INFO - 'tests': ['tp5n']}}, 02:57:12 INFO - 'talos.zip': {'path': '', 02:57:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:57:12 INFO - Running post-action listener: _resource_record_post_action 02:57:12 INFO - [mozharness: 2016-10-13 09:57:12.684692Z] Finished populate-webroot step (success) 02:57:12 INFO - [mozharness: 2016-10-13 09:57:12.684775Z] Running create-virtualenv step. 02:57:12 INFO - Running pre-action listener: _resource_record_pre_action 02:57:12 INFO - Running main action method: create_virtualenv 02:57:12 INFO - Creating virtualenv /builds/slave/test/build/venv 02:57:12 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 02:57:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:57:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:57:12 INFO - Using real prefix '/tools/python27' 02:57:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:57:13 INFO - Installing distribute.............................................................................................................................................................................................done. 02:57:16 INFO - Installing pip.................done. 02:57:16 INFO - Return code: 0 02:57:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 02:57:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 02:57:16 INFO - Reading from file tmpfile_stdout 02:57:16 INFO - Output received: 02:57:16 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) 02:57:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:57:16 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')]} 02:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:16 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')]} 02:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:16 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:16 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 02:57:16 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 02:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:16 INFO - 'HOME': '/Users/cltbld', 02:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:16 INFO - 'LOGNAME': 'cltbld', 02:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:16 INFO - 'MOZ_NO_REMOTE': '1', 02:57:16 INFO - 'NO_EM_RESTART': '1', 02:57:16 INFO - 'PAGER': '/bin/cat', 02:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:16 INFO - 'PWD': '/builds/slave/test', 02:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:16 INFO - 'SHELL': '/bin/bash', 02:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:16 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:16 INFO - 'USER': 'cltbld', 02:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:16 INFO - 'XPC_FLAGS': '0x0', 02:57:16 INFO - 'XPC_SERVICE_NAME': '0', 02:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:16 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 02:57:16 INFO - Cleaning up... 02:57:16 INFO - Return code: 0 02:57:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:57:16 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')]} 02:57:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:16 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')]} 02:57:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:16 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:16 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 02:57:16 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 02:57:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:16 INFO - 'HOME': '/Users/cltbld', 02:57:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:16 INFO - 'LOGNAME': 'cltbld', 02:57:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:16 INFO - 'MOZ_NO_REMOTE': '1', 02:57:16 INFO - 'NO_EM_RESTART': '1', 02:57:16 INFO - 'PAGER': '/bin/cat', 02:57:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:16 INFO - 'PWD': '/builds/slave/test', 02:57:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:16 INFO - 'SHELL': '/bin/bash', 02:57:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:16 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:16 INFO - 'USER': 'cltbld', 02:57:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:16 INFO - 'XPC_FLAGS': '0x0', 02:57:16 INFO - 'XPC_SERVICE_NAME': '0', 02:57:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:17 INFO - Downloading/unpacking psutil>=3.1.1 02:57:17 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 02:57:17 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 02:57:17 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 02:57:17 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 02:57:17 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 02:57:17 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 02:57:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:57:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:21 INFO - Installing collected packages: psutil 02:57:21 INFO - Running setup.py install for psutil 02:57:21 INFO - building 'psutil._psutil_osx' extension 02:57:21 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 02:57:21 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 02:57:21 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 02:57:21 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 02:57:22 INFO - building 'psutil._psutil_posix' extension 02:57:22 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 02:57:22 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:57:22 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:57:22 INFO - ^ 02:57:22 INFO - 1 warning generated. 02:57:22 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 02:57:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:57:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:57:22 INFO - Successfully installed psutil 02:57:22 INFO - Cleaning up... 02:57:22 INFO - Return code: 0 02:57:22 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:57: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 mozsystemmonitor==0.3 02:57:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:22 INFO - 'HOME': '/Users/cltbld', 02:57:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:22 INFO - 'LOGNAME': 'cltbld', 02:57:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:22 INFO - 'MOZ_NO_REMOTE': '1', 02:57:22 INFO - 'NO_EM_RESTART': '1', 02:57:22 INFO - 'PAGER': '/bin/cat', 02:57:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:22 INFO - 'PWD': '/builds/slave/test', 02:57:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:22 INFO - 'SHELL': '/bin/bash', 02:57:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:22 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:22 INFO - 'USER': 'cltbld', 02:57:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:22 INFO - 'XPC_FLAGS': '0x0', 02:57:22 INFO - 'XPC_SERVICE_NAME': '0', 02:57:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57:25 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:57:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:57:25 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:57:25 INFO - Installing collected packages: mozsystemmonitor 02:57:25 INFO - Running setup.py install for mozsystemmonitor 02:57:25 INFO - Successfully installed mozsystemmonitor 02:57:25 INFO - Cleaning up... 02:57:25 INFO - Return code: 0 02:57:25 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:25 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:25 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 02:57:25 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 02:57:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:25 INFO - 'HOME': '/Users/cltbld', 02:57:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:25 INFO - 'LOGNAME': 'cltbld', 02:57:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:25 INFO - 'MOZ_NO_REMOTE': '1', 02:57:25 INFO - 'NO_EM_RESTART': '1', 02:57:25 INFO - 'PAGER': '/bin/cat', 02:57:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:25 INFO - 'PWD': '/builds/slave/test', 02:57:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:25 INFO - 'SHELL': '/bin/bash', 02:57:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:25 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:25 INFO - 'USER': 'cltbld', 02:57:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:25 INFO - 'XPC_FLAGS': '0x0', 02:57:25 INFO - 'XPC_SERVICE_NAME': '0', 02:57:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:25 INFO - Downloading/unpacking jsonschema==2.5.1 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:28 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:57:28 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 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 02:57:28 INFO - Downloading functools32-3.2.3-2.tar.gz 02:57:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:57:29 INFO - warning: no files found matching '*.txt' 02:57:29 INFO - no previously-included directories found matching 'build' 02:57:29 INFO - no previously-included directories found matching 'dist' 02:57:29 INFO - no previously-included directories found matching '.git*' 02:57:29 INFO - Installing collected packages: jsonschema, functools32 02:57:29 INFO - Running setup.py install for functools32 02:57:29 INFO - warning: no files found matching '*.txt' 02:57:29 INFO - no previously-included directories found matching 'build' 02:57:29 INFO - no previously-included directories found matching 'dist' 02:57:29 INFO - no previously-included directories found matching '.git*' 02:57:29 INFO - Successfully installed jsonschema functools32 02:57:29 INFO - Cleaning up... 02:57:29 INFO - Return code: 0 02:57:29 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:29 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:29 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 02:57:29 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 02:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:29 INFO - 'HOME': '/Users/cltbld', 02:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:29 INFO - 'LOGNAME': 'cltbld', 02:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:29 INFO - 'MOZ_NO_REMOTE': '1', 02:57:29 INFO - 'NO_EM_RESTART': '1', 02:57:29 INFO - 'PAGER': '/bin/cat', 02:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:29 INFO - 'PWD': '/builds/slave/test', 02:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:29 INFO - 'SHELL': '/bin/bash', 02:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:29 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:29 INFO - 'USER': 'cltbld', 02:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:29 INFO - 'XPC_FLAGS': '0x0', 02:57:29 INFO - 'XPC_SERVICE_NAME': '0', 02:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:29 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:57:29 INFO - Cleaning up... 02:57:29 INFO - Return code: 0 02:57:29 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:29 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:29 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 02:57:29 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 02:57:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:29 INFO - 'HOME': '/Users/cltbld', 02:57:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:29 INFO - 'LOGNAME': 'cltbld', 02:57:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:29 INFO - 'MOZ_NO_REMOTE': '1', 02:57:29 INFO - 'NO_EM_RESTART': '1', 02:57:29 INFO - 'PAGER': '/bin/cat', 02:57:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:29 INFO - 'PWD': '/builds/slave/test', 02:57:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:29 INFO - 'SHELL': '/bin/bash', 02:57:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:29 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:29 INFO - 'USER': 'cltbld', 02:57:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:29 INFO - 'XPC_FLAGS': '0x0', 02:57:29 INFO - 'XPC_SERVICE_NAME': '0', 02:57:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:29 INFO - Downloading/unpacking blobuploader==1.2.4 02:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:32 INFO - Downloading blobuploader-1.2.4.tar.gz 02:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:57:32 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 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 02:57:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:57:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57: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 02:57:33 INFO - Downloading docopt-0.6.1.tar.gz 02:57:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:57:33 INFO - Installing collected packages: blobuploader, requests, docopt 02:57:33 INFO - Running setup.py install for blobuploader 02:57:33 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:57:33 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:57:33 INFO - Running setup.py install for requests 02:57:34 INFO - Running setup.py install for docopt 02:57:34 INFO - Successfully installed blobuploader requests docopt 02:57:34 INFO - Cleaning up... 02:57:34 INFO - Return code: 0 02:57:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:34 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:34 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 02:57:34 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 02:57:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:34 INFO - 'HOME': '/Users/cltbld', 02:57:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:34 INFO - 'LOGNAME': 'cltbld', 02:57:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:34 INFO - 'MOZ_NO_REMOTE': '1', 02:57:34 INFO - 'NO_EM_RESTART': '1', 02:57:34 INFO - 'PAGER': '/bin/cat', 02:57:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:34 INFO - 'PWD': '/builds/slave/test', 02:57:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:34 INFO - 'SHELL': '/bin/bash', 02:57:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:34 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:34 INFO - 'USER': 'cltbld', 02:57:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:34 INFO - 'XPC_FLAGS': '0x0', 02:57:34 INFO - 'XPC_SERVICE_NAME': '0', 02:57:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:34 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5MGZ8w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:34 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-dVEBuQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ViZ66Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-5oJsGU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-rhD6Dc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Uzvk15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-hto2LK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-tAzJHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-DuO0ia-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-EFOaY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-J7v8PH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:35 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-DhFbx7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:36 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-XYyz7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:36 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-9kHVRg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:36 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-0AiBsi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:36 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-bKzfnX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:36 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-y68qCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:36 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:57:36 INFO - Running setup.py install for manifestparser 02:57:36 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:57:36 INFO - Running setup.py install for mozcrash 02:57:36 INFO - Running setup.py install for mozdebug 02:57:36 INFO - Running setup.py install for mozdevice 02:57:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Running setup.py install for mozfile 02:57:37 INFO - Running setup.py install for mozhttpd 02:57:37 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Running setup.py install for mozinfo 02:57:37 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Running setup.py install for mozInstall 02:57:37 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:57:37 INFO - Running setup.py install for mozleak 02:57:37 INFO - Running setup.py install for mozlog 02:57:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:57:38 INFO - Running setup.py install for moznetwork 02:57:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:57:38 INFO - Running setup.py install for mozprocess 02:57:38 INFO - Running setup.py install for mozprofile 02:57:38 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:57:38 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:57:38 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:57:38 INFO - Running setup.py install for mozrunner 02:57:38 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:57:38 INFO - Running setup.py install for mozscreenshot 02:57:38 INFO - Running setup.py install for moztest 02:57:38 INFO - Running setup.py install for mozversion 02:57:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:57:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:57:39 INFO - Cleaning up... 02:57:39 INFO - Return code: 0 02:57:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57: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')]} 02:57:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57: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')]} 02:57:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:39 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:39 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 02:57:39 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 02:57:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:39 INFO - 'HOME': '/Users/cltbld', 02:57:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:39 INFO - 'LOGNAME': 'cltbld', 02:57:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:39 INFO - 'MOZ_NO_REMOTE': '1', 02:57:39 INFO - 'NO_EM_RESTART': '1', 02:57:39 INFO - 'PAGER': '/bin/cat', 02:57:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:39 INFO - 'PWD': '/builds/slave/test', 02:57:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:39 INFO - 'SHELL': '/bin/bash', 02:57:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:39 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:39 INFO - 'USER': 'cltbld', 02:57:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:39 INFO - 'XPC_FLAGS': '0x0', 02:57:39 INFO - 'XPC_SERVICE_NAME': '0', 02:57:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:57:39 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vqzQmB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:57:39 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)) 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:57:39 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Xr1GIR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:57:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:57:39 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-_TvuRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:57:39 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)) 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:57:39 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-vEv_T7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:57:39 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)) 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:57:39 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-x85wdD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:57:39 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)) 02:57:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:57:39 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-wbtXYw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-BU06bA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-OUIHTD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-PbyXJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-CMqycO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-LPZzQi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-KTEvsy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-3vQhPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-ZcPmZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:57:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-u1_KOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:57:40 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)) 02:57:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:57:40 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-kvCKdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:57:41 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)) 02:57:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:57:41 INFO - Running setup.py (path:/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/pip-Hiw4mm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:57:41 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)) 02:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:57:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:57:41 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)) 02:57:41 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)) 02:57:41 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)) 02:57:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:57:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:57:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:57:43 INFO - Downloading blessings-1.6.tar.gz 02:57:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:57:43 INFO - Installing collected packages: blessings 02:57:43 INFO - Running setup.py install for blessings 02:57:44 INFO - Successfully installed blessings 02:57:44 INFO - Cleaning up... 02:57:44 INFO - Return code: 0 02:57:44 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:57:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:44 INFO - Reading from file tmpfile_stdout 02:57:44 INFO - Current package versions: 02:57:44 INFO - blessings == 1.6 02:57:44 INFO - blobuploader == 1.2.4 02:57:44 INFO - docopt == 0.6.1 02:57:44 INFO - functools32 == 3.2.3-2 02:57:44 INFO - jsonschema == 2.5.1 02:57:44 INFO - manifestparser == 1.1 02:57:44 INFO - mozInstall == 1.12 02:57:44 INFO - mozcrash == 0.17 02:57:44 INFO - mozdebug == 0.1 02:57:44 INFO - mozdevice == 0.48 02:57:44 INFO - mozfile == 1.2 02:57:44 INFO - mozhttpd == 0.7 02:57:44 INFO - mozinfo == 0.9 02:57:44 INFO - mozleak == 0.1 02:57:44 INFO - mozlog == 3.4 02:57:44 INFO - moznetwork == 0.27 02:57:44 INFO - mozprocess == 0.23 02:57:44 INFO - mozprofile == 0.28 02:57:44 INFO - mozrunner == 6.12 02:57:44 INFO - mozscreenshot == 0.1 02:57:44 INFO - mozsystemmonitor == 0.3 02:57:44 INFO - moztest == 0.7 02:57:44 INFO - mozversion == 1.4 02:57:44 INFO - psutil == 3.1.1 02:57:44 INFO - requests == 1.2.3 02:57:44 INFO - wsgiref == 0.1.2 02:57:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:44 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 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:44 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 02:57:44 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 02:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:44 INFO - 'HOME': '/Users/cltbld', 02:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:44 INFO - 'LOGNAME': 'cltbld', 02:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:44 INFO - 'MOZ_NO_REMOTE': '1', 02:57:44 INFO - 'NO_EM_RESTART': '1', 02:57:44 INFO - 'PAGER': '/bin/cat', 02:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:44 INFO - 'PWD': '/builds/slave/test', 02:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:44 INFO - 'SHELL': '/bin/bash', 02:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:44 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:44 INFO - 'USER': 'cltbld', 02:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:44 INFO - 'XPC_FLAGS': '0x0', 02:57:44 INFO - 'XPC_SERVICE_NAME': '0', 02:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 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)) 02:57:44 INFO - Cleaning up... 02:57:44 INFO - Return code: 0 02:57:44 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:57:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:57:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:57:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:57:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101147490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1010bd570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb92adb1820>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, '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.iQb96mJcDU/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.0g9cq1dyNU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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 02:57:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:57:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:57:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:57:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:57:44 INFO - 'HOME': '/Users/cltbld', 02:57:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:57:44 INFO - 'LOGNAME': 'cltbld', 02:57:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:57:44 INFO - 'MOZ_NO_REMOTE': '1', 02:57:44 INFO - 'NO_EM_RESTART': '1', 02:57:44 INFO - 'PAGER': '/bin/cat', 02:57:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:57:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:57:44 INFO - 'PWD': '/builds/slave/test', 02:57:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:57:44 INFO - 'SHELL': '/bin/bash', 02:57:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:57:44 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:57:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:57:44 INFO - 'USER': 'cltbld', 02:57:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:57:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:57:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:57:44 INFO - 'XPC_FLAGS': '0x0', 02:57:44 INFO - 'XPC_SERVICE_NAME': '0', 02:57:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:57:44 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:57:44 INFO - Cleaning up... 02:57:44 INFO - Return code: 0 02:57:44 INFO - Running post-action listener: _resource_record_post_action 02:57:44 INFO - Running post-action listener: _start_resource_monitoring 02:57:44 INFO - Starting resource monitoring. 02:57:44 INFO - [mozharness: 2016-10-13 09:57:44.996619Z] Finished create-virtualenv step (success) 02:57:44 INFO - [mozharness: 2016-10-13 09:57:44.997522Z] Running install step. 02:57:44 INFO - Running pre-action listener: _resource_record_pre_action 02:57:44 INFO - Running main action method: install 02:57:44 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:57:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:57:45 INFO - Reading from file tmpfile_stdout 02:57:45 INFO - Detecting whether we're running mozinstall >=1.0... 02:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:57:45 INFO - Reading from file tmpfile_stdout 02:57:45 INFO - Output received: 02:57:45 INFO - Usage: mozinstall [options] installer 02:57:45 INFO - Options: 02:57:45 INFO - -h, --help show this help message and exit 02:57:45 INFO - -d DEST, --destination=DEST 02:57:45 INFO - Directory to install application into. [default: 02:57:45 INFO - "/builds/slave/test"] 02:57:45 INFO - --app=APP Application being installed. [default: firefox] 02:57:45 INFO - mkdir: /builds/slave/test/build/application 02:57:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:57:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:58:19 INFO - Reading from file tmpfile_stdout 02:58:19 INFO - Output received: 02:58:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:58:19 INFO - Running post-action listener: _resource_record_post_action 02:58:19 INFO - [mozharness: 2016-10-13 09:58:19.952160Z] Finished install step (success) 02:58:19 INFO - [mozharness: 2016-10-13 09:58:19.952289Z] Running run-tests step. 02:58:19 INFO - Running pre-action listener: _resource_record_pre_action 02:58:19 INFO - Running main action method: run_tests 02:58:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:58:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:58:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:58:19 INFO - 2016-10-13 02:58:19.966 screenresolution[1639:8035] starting screenresolution argv=screenresolution get 02:58:19 INFO - 2016-10-13 02:58:19.969 screenresolution[1639:8035] Display 0: 1600x1200x32@60 02:58:19 INFO - 2016-10-13 02:58:19.975 screenresolution[1640:8037] starting screenresolution argv=screenresolution list 02:58:19 INFO - Available Modes on Display 0 02:58:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:58:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:58:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:58:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:58:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:58:20 INFO - Intel Iris: 02:58:20 INFO - Chipset Model: Intel Iris 02:58:20 INFO - Type: GPU 02:58:20 INFO - Bus: Built-In 02:58:20 INFO - VRAM (Dynamic, Max): 1536 MB 02:58:20 INFO - Vendor: Intel (0x8086) 02:58:20 INFO - Device ID: 0x0a2e 02:58:20 INFO - Revision ID: 0x0009 02:58:20 INFO - Displays: 02:58:20 INFO - Display: 02:58:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:58:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:58:20 INFO - Display Serial Number: 1600x1200 60 02:58:20 INFO - Main Display: Yes 02:58:20 INFO - Mirror: Off 02:58:20 INFO - Online: Yes 02:58:20 INFO - Rotation: Supported 02:58:20 INFO - Return code: 0 02:58:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:58:20 INFO - Python 2.7.3 02:58:20 INFO - Return code: 0 02:58:20 INFO - grabbing minidump binary from tooltool 02:58:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:58:20 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 0x100f5bd30>, '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 0x100d81db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f53b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:58:20 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 02:58:20 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 02:58:20 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 02:58:20 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:58:20 INFO - Return code: 0 02:58:20 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:58:20 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:58:20 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:58:20 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:58:20 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:58:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 02:58:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0127 --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 02:58:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iQb96mJcDU/Render', 02:58:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:58:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:58:20 INFO - 'HOME': '/Users/cltbld', 02:58:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:58:20 INFO - 'LOGNAME': 'cltbld', 02:58:20 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:58:20 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:58:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:58:20 INFO - 'MOZ_NO_REMOTE': '1', 02:58:20 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:58:20 INFO - 'NO_EM_RESTART': '1', 02:58:20 INFO - 'PAGER': '/bin/cat', 02:58:20 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:58:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:58:20 INFO - 'PWD': '/builds/slave/test', 02:58:20 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:58:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:58:20 INFO - 'SHELL': '/bin/bash', 02:58:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0g9cq1dyNU/Listeners', 02:58:20 INFO - 'TMPDIR': '/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/', 02:58:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:58:20 INFO - 'USER': 'cltbld', 02:58:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:58:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:58:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:58:20 INFO - 'XPC_FLAGS': '0x0', 02:58:20 INFO - 'XPC_SERVICE_NAME': '0', 02:58:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:58:20 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0127', '--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 02:58:20 INFO - mozversion application_buildid: 20161013004823 02:58:20 INFO - mozversion application_changeset: 7480f3d08b18ee0d347a944020509c2d57bb5cf0 02:58:20 INFO - mozversion application_display_name: Nightly 02:58:20 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:58:20 INFO - mozversion application_name: Firefox 02:58:20 INFO - mozversion application_remotingname: firefox 02:58:20 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 02:58:20 INFO - mozversion application_vendor: Mozilla 02:58:20 INFO - mozversion application_version: 52.0a1 02:58:20 INFO - mozversion platform_buildid: 20161013004823 02:58:20 INFO - mozversion platform_changeset: 7480f3d08b18ee0d347a944020509c2d57bb5cf0 02:58:20 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 02:58:20 INFO - mozversion platform_version: 52.0a1 02:58:20 INFO - using testdate: 1476352700 02:58:20 INFO - actual date: 1476352700 02:58:20 INFO - starting webserver on 'localhost:49274' 02:58:20 INFO - SUITE-START | Running 2 tests 02:58:20 INFO - TEST-START | dromaeo_css 02:58:20 INFO - operating with platform_type : mac_ 02:58:20 INFO - Initialising browser for dromaeo_css test... 02:58:20 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpg23iu3/profile http://localhost:49274/getInfo.html) 02:58:22 INFO - PROCESS | 1650 | __metrics Screen width/height:1600/1200 02:58:22 INFO - PROCESS | 1650 | colorDepth:24 02:58:22 INFO - PROCESS | 1650 | Browser inner width/height: 1024/689 02:58:23 INFO - PROCESS | 1650 | __metrics 02:58:23 INFO - PROCESS | 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:58:23 INFO - PROCESS | 1650 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:58:23 INFO - TEST-INFO | 1650: exit 0 02:58:23 INFO - Browser initialized. 02:58:23 INFO - Running cycle 1/1 for dromaeo_css test... 02:58:23 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpg23iu3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:58:36 INFO - PROCESS | 1653 | RSS: Main: 234950656 02:58:36 INFO - PROCESS | 1653 | 02:59:27 INFO - PROCESS | 1653 | 1476352767794 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] 03:01:41 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 03:01:42 INFO - PROCESS | 1653 | RSS: Main: 404291584 03:01:42 INFO - PROCESS | 1653 | 03:04:45 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 03:04:46 INFO - PROCESS | 1653 | RSS: Main: 370225152 03:04:46 INFO - PROCESS | 1653 | 03:07:48 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 03:07:49 INFO - PROCESS | 1653 | RSS: Main: 346824704 03:07:49 INFO - PROCESS | 1653 | 03:10:51 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 03:10:52 INFO - PROCESS | 1653 | RSS: Main: 349245440 03:10:52 INFO - PROCESS | 1653 | 03:13:55 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 03:13:56 INFO - PROCESS | 1653 | RSS: Main: 338722816 03:13:56 INFO - PROCESS | 1653 | 03:16:59 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 03:17:00 INFO - PROCESS | 1653 | RSS: Main: 514338816 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | __start_tp_report 03:17:00 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 03:17:00 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 03:17:00 INFO - PROCESS | 1653 | |i|pagename|runs| 03:17:00 INFO - PROCESS | 1653 | |0;dojo.html;2990.67102618712;3280.671932806719;3303.5375047493353;3318.5022246662984;3347.548081009063;5365.597580181482;5444.700541470223;5474.863128421792;5495.532879705225;5497.725113744316;5389.676619402839;5445.646033007852;5495.862603434916;5502.339719233688;5513.283273174492;4913.533214344649;4983.401991760994;5023;5033.874153146164;5049.570786483135;4882.43851957026;5124.308218390507;5135.05001574708;5143.279940808271;5185.014847179043;4455.952851079994;5103.617228707862;5106.64253502255;5125.051865404912;5154.252633368178;4939.53074457926;4990.001999600058;4992.575631071363;5000.499950004971;5065.848024559269;5005.074061298671;5025.422076461217;5046.949530504721;5082.669626474304;5082.974585127087;5492.642978206443;5522.337319521683;5531.944680553183;5544.085225937715;5566.443355664401;5290.973545132289;5399.8390346075785;5847.883042339172;5873.089671100986;5976.551758618078;5912.320083190445;5968.432998865101;6007.60950538218;6024;6100.420460056288;5554.888902221933;5574.804881829116;5578.386377498472;5578.44215578447;5587.413321601254;6150.292716337589;6177;6203.286622038433;6224.688765561704;6236.937630623635;5630.5495560355075;5713.314402271671;5715.371309155989;5768.423157684198;5771.365149833515;5882.764689412375;5884.676342801187;5899.970500147472;5912.497437717842;5928.79249226275;5791.913121303184;5822.1266809978;5844.649321040752;5881.9705901470215;5882.088276317178;5903.2325797646035;5903.26209223847;5917.437843404869;5926.377730338339;5930.347661757203;4761.500042495557;4764.99935013651;4765.404324459442;4766.23740201573;4767.118083154627;4207.474065741782;4213.620774130343;4229.154169166179;4244.427002354642;4342.761148136819;5528.778848846001;5543.168524721244;5544.972275138679;5573;5612.663240205601;6058.091286307089;6061.121137435047;6086.3000754913755;6087.05650624154;6096.847578810565;5478.342598888159;5545.390007099216;5546.69493177871;5593.188987596775;5600.159976003632;5177.430482646906;5229.032628963578;5235.895282094337;5245.10833158364;5270.341207349082;6221.097940798559;6250.249970003629;6258.405451482284;6272.6236425814595;6288.874222515616;5855.677937713466;5958.433948774859;6039.849003774941;6066.757329706938;6080.452759251689;6123.404636889863;6130.696388836325;6150.015997440388;6177.660228687465;6186.566940314135;5897.469227769521;5924.407559244041;5928.733207005609;5936.347001829881;5960.672163031075;6058.57589968698;6060.90908636362;6062.060380641008;6069.453749162597;6076.118962750465;5569.637973531708;5617.887642247214;5745.080787074048;5746.281714785652;5761.135829625589;5706.828795135986;5798.6810725410505;5801.361850196559;5818.8254352369495;5866.823995280149;5600.467955544217;5675.602707810413;5681.488666020078;5689.800856969986;5693.943060569507;5623.606347555632;5723.914141287801;5770.769169233184;5788.507976822018;5824.854378640568;5650.802221922213;5698.8290351289525;5773.6824474654295;5780.306363236438;5792.594518383673 03:17:00 INFO - PROCESS | 1653 | |1;ext.html;510.63999880084543;535.6170338208183;536.3483367708234;550.1967127993131;551.771015028763;30371;31952.520712189307;39480.40779388312;40312;40391.5862944797;42182.57817421825;42487.15025699484;42535.574644253546;42725.57274427255;42730.57269427305;9444.33889627728;9582.281328900326;9587.760305992371;9600.087991640783;9614.663486777965;9288;9402.106799854002;9408.57661405235;9412.764680882965;9417.529123543829;9372.718818435424;9450.19673327768;9451.432914025147;9457.243420526376;9465;9390.577424015935;9431.386959847656;9438.622455101788;9459.668911588062;9505.524723763785;9442.38624489406;9517.90482095184;9529.190018848407;9544.90455095447;9605.951970240174;39634.60365396338;40012.39981400281;40021.39967900484;40111.39832902509;40200.79899600513;3259.315543735818;3504.1940353718787;3510.1049232445816;3542.9114272143147;3574.767640103385;30208.69791302081;30570.84714576435;30753.38493230123;31508.527372089437;31598;3136.1218858719594;3151.8266495342746;3175.984120079408;3191.186247506871;3239.8704051838135;475836;478880.60559697327;479157;479371.60314198554;479506;30049.399012020076;57636;57956.71021644865;58039.709801451565;58111.41888581144;34139.31721365559;34192.487112692834;34397.828010860285;34437.82781086129;34442;14188.503402380868;14315.284235788376;14317.85682143186;14319.35562899672;14342.928285358506;12213.389330533615;12310.81533776994;12313.199642023419;12330.260184388966;12349.567765128177;7638;7643.503172293783;7666.348360389318;7717.922820771832;7735.265149810759;3180.6978337057944;3229.1765599771907;3231.628362738268;3236.6116066072223;3265.869365225364;894.2130924786146;947.4646824544249;956.5360800011989;1014.7361685961703;1053.9683809485728;19107.61784764297;29801.85099074534;29815.701842981292;30459.543106853416;30481.847590761903;1013.9340942838692;1056.487603512296;1070.4808168038496;1082.783443311325;1117.4580328540815;12769.553065642658;15427.228638568251;15489.380424783109;15676.529704108667;15896.841031589536;679550;685632;685914;686407;688118;56729.43270567324;57285;57383.71308143516;57398.426015740144;57532;1223.3332833605707;1272.0141890035297;1332.060897067565;1367.4940272099188;1389.6248013036222;29764.255893601967;30676.539851901794;30983.380332393677;31127.53308700326;31179.84410077935;43786.78106609447;44278;44307;44315.670529884155;44400.333994990746;443618;445219;445221;445999;448450;21487.892560537097;23134.652980204974;23609.40976475602;26277.73722262753;27053.72946270591;45817;45917.54082459133;46505.53494465012;47425.05149897052;47435.81410464766;29906.700932990392;35978.64021359753;37905.05237369088;38136.809315953244;38655.80672096622;36533.26933461369;38218.42672359861;38931.11178491015;38960.02599935024;39325;33748;37339.25321493501;37405.62594374021;37590.43614345732;38242.426363605126;29033;31381.52927706132;32323.51514727234;33969.15077122993;34134.829325853214;24877.62683559712;24882.626760598967;24888.62667060032;25827.966881325287;25843.612345815203 03:17:00 INFO - PROCESS | 1653 | |2;jquery.html;366.82942431769237;367.2214904402666;373.09524403321956;387.1753165756937;397.4435789894148;15388.690372073259;15726.056436613811;19170;20949.895250523747;21001.789982100174;19946.301879434224;20201;20661.793382066175;20834.270800521947;20858;5331.333583302077;5789.9710501447435;5849.444302791228;5862.618929769551;5865.8240252792275;4912.23159662523;5711.314642242936;5836.124581312794;5836.533077353823;5845.561582881301;5773.5381169506545;6141.740943106653;6389.2971773105155;6459;6481.28705842357;6189.319174890758;6419.26178489475;6433.678316084177;6444.709988050526;6486.156799616019;5624.606277560572;5626.212330273765;5635.154726791015;5636.802711905105;5639.774409023676;23965.88017059921;25090.749092509024;25402.87298563514;25754.87122564375;25915.352116197057;2100.0759665747173;2167.6856855755827;2319.52449747803;2329.5108027314286;2354.1642716835618;9469.573869175903;9768.462734549597;9982.35114717541;10264.486775661187;10385.896141038644;1833.2575307000686;1851.3150134450204;1865.3844231403605;1912.4740696308488;1917.4726950088698;109657;117152;120017;121450;123609.38195309056;10642.840357394645;10966.670999869857;11387.886121138848;11448.255863368855;11640.126990475748;11761.705957351134;14265;14273.286335683382;14302.570922872123;14306.9985101044;8051.5571643559;8329.125441828643;8369.204925532065;8497.490150590984;8541.572921353907;8239;8249.422540422232;8258.669653213925;8445.197706218023;8447.324214062983;3647.3434781739543;4359.58043285973;4490;4560.8175672973375;4612.76936153191;1857.535616095976;1920.0879582198345;1926.4317026476942;1927.3447028010264;1928.6817675083735;775.6470805783355;831.1854382704983;872.7425409504211;890.2343984173606;918.4672889723944;20759.792402076087;21358.359249222547;21372.824494652632;21391.358259252247;21413.67879481809;807.168616325186;958.5351104714208;964.3394274921701;969.5976169889558;973.5862258540234;10512.526936287886;11188.328700278009;11189.04893084097;11215.887841121648;11356.26184298018;539452;539492;544008;554883;555698;89912.46291526264;124474;127936;130440;135951.96072058927;921.7373048681094;931.5714771205186;963.797602503468;1017.064300843226;1020.1226944827442;17871.92768433898;18761.6247675043;19275.903620481808;19286.7106993398;19577.902110489358;53230.080089589654;53796.73101634467;54406.72796635992;56179;56540.43459565352;74308.62845685738;80281;80716.36360001304;80903;82244;5472.315960504937;6259.186305780219;7499.287567681062;8234;8331.583420829056;10130.544125514221;12847.421866016239;13028.064112495027;13046.804297935349;13346.86653133495;11106.888931110909;11385.772284554097;11466.713332166762;11547.653570392902;11662.53349865996;10991.175661825555;11124.443777810915;11156.163287753614;11577.247478913843;11621.477033533341;11054.723631908944;11509.942450287695;11738;11768.117391195523;11900.642980710592;7943.960280198562;8103.87844182326;8290.751277461686;8437.156284371515;8441.986961564651;10127.7468063299;10843;11120;11294.717632058935;11405.828912566152 03:17:00 INFO - PROCESS | 1653 | |3;mootools.html;1462.115420170797;1481.1557412275004;1640.8523232909042;1834.4955137337222;1849.9075046247692;8345.290650294724;8842.955785221073;8859.025507194203;8871.467711937288;8882;6367;8031.518108913469;8057.033156021271;8414.326853851693;8621.137886211376;2315.849469784467;2354;2357.033616217351;2361.551305252005;2364.2316247219637;1932.8646994710305;2300.2409204962323;2311.2603966730653;2353.0822979038103;2358.41039740065;2317.8261630377706;2345.6012477878803;2347.9178228762;2348.342464110052;2361.551305252005;2320.1879342230254;2329.1615018593293;2332.615118505453;2336.8714720690355;2337.4623836517526;2242.5178586603956;2271.057511132878;2273.693051438051;2274.363178310076;2274.6360582306916;8654.00478944923;8716.346274029473;8782.297416206751;8787.209151176425;8805.735827925173;5097.108006098918;5284.365876094855;5285.524302812765;5297.020051290524;5303.28405665238;3116.9064928051966;3387.3733359328357;3620.475031120499;3624.5650521937273;3664.633536646341;991.1674193677345;1050.1913526584563;1068.6526878764355;1072.624581396513;1072.8068947589431;11515.942420287929;11528.365939873303;11661.183717139804;11661.300321980709;11663.24188927717;4518.118966801508;4533.818647254073;4717.056588682277;4723.6929599575915;4724.511778789671;9863.852042219372;9890.109890109925;9900.356476828983;9906.851397229048;9921.404715716935;5302.2046692996355;5305;5318.73406329682;5322;5325.707086110301;4320.308750599889;4343.2399330117105;4371.519132895379;4848.442429120696;4885.291632713237;3816.5420149581673;3830.559485659129;3902.23906338267;3946.881593552198;3960.702947278908;1198.6404078776507;1216.2937464142212;1227.8403807505147;1233.7902556565225;1257.9811302830465;805.9890573020351;869.5000374784524;884.1732979663906;921.6589861751152;964.19008033251;5553.861153471114;5777.682227477445;5984.461398474158;6078.696065196723;6079.05774604937;404.1069236986293;413.9006638406761;420.8064290426438;423.9576042395736;427.36322878910823;3547.9822600886832;3833.0800607853917;3914.745541539791;3947.401302472503;4054.4729185205733;8778.956105219433;10741.462926853626;10745.570177192825;10746.92530746919;10759.83860242097;10687.465626718633;10703.304285221435;10703.625373111903;10707.571697132184;10715.571377144826;7557.715188417872;7972;8055.677772889136;8138.023437187576;8338.666453341799;4620.075984802986;5018.44797072329;5258.842234732964;5921.5854890159;6099.176611157614;8696.521691307038;9733.513324333613;9975.301728878909;10066.899331006889;10126.038026387776;8925.973513046083;9284.860727088964;10204.948975255076;10234.795304094025;10243.795124097625;6711;6940.41006514452;6956.721731130698;7426.480146389701;7529.853991612365;7982.521048737094;8103.1491693371345;8181.100078991425;8194.631241594023;8284.171582841667;7325.7802265932105;7411.592362420119;7434.73978410753;7537.773866784005;7580.3556697681315;7038.155421349471;7264.527805692613;7318.81702957409;7340.889886651597;7377.299156580117;6849.452043836382;6850.451963842781;6937.167539895245;6939.4448444125355;6959.19969203548;4264.739687965383;4975.228839529878;4984.102861484823;5020.849374518771;5162.225666150108;4988.27669987857;5017.899642007213;5065.645404821627;5128.897422051464;5133.230015497706 03:17:00 INFO - PROCESS | 1653 | |4;prototype.html;293.59923704143836;293.79727987688494;302.8379816798014;307.3514883595613;308.52641195764505;4168.916621667565;4397.868063958086;5037.899242015162;5088.720120393377;5093.3887933447995;4175.70336315027;4658.743769092698;4956.306117143594;4969.353983982086;5084.220522817012;3344.3812894614452;3739.7195210359196;3760.6615404613513;3838.5777564467885;3902.024493876531;2817.4365126974585;3724.8510059597584;3925.509311336083;3951.9844879111715;3977.4232736253225;3150.7321877640316;3728.8322025508783;3746.906327341811;3880.553736320331;3914.2367238388524;3895.2793733159315;3899.688024958025;3907.538250901942;3919.2749341371937;3985.9800700996598;2813.4513769814894;3415.8633654653786;3435.7423193260356;3443.6384179661277;3445.2248244145117;3735.0662334416397;3813.2649644412004;4757.809687612491;4764.190087685102;4924.433690125646;3640.381135207021;4258.2966813274625;4275.819332140631;4344.103906269122;4483.825344707814;4318.330658747899;4811.735354555498;5306.389765177016;5324.440933701944;5343.946560534384;3303.852495877699;3412.283420481702;3725.9068523286865;3731.3022574728434;3744.9438258426144;4431.9778401108115;5493.862653433696;5567.137093750475;5577.69322687252;5597.776088956478;3285.983570082134;3611.3138503684218;3789.1474418256225;3793.696504279651;3802.421994872097;3022.501287287617;3189.8564564594185;3214.72674822638;3849.480320156743;3863.9806800965816;3639.29033838399;4208.473940757405;4399.38408622793;4416.271315232951;4433.7118087324225;4836.371271734652;5087.669301495391;5331.840604555161;5338.626296159309;5365.195220716923;1255.79907214847;1285.7299967007034;1309.2864388908067;1309.299524754252;1317.5322760419913;805.9113497515268;841.1252297610539;863.8920134983127;871.4161511787117;885.4908427653996;4509.421702404198;4978.4025916890205;4980.2778597103215;5005.299258103868;5015.072211640785;4915.48387419323;5372.650777699437;5470.53500452466;5494.36814766307;5500.972495137498;4322.514554131387;4619.5122682374595;4987.60099192071;4991.975040124848;5052.120291433847;2630.473905218964;2803.453326601293;2847.547750647185;2859.070801989362;2873.8504598160903;5119.8720031998755;5326.334208223972;5461.907618476241;5592.692401917852;5625.746841392147;5077.31894376574;5334.759935802898;5417.024935511646;5515.558755299567;5620.634658747168;4736.5973892219545;5565.193047008162;5602.887942241132;5611.9719401403545;5615.999999999918;5110.310108135354;5359.892802144013;5384.461553844584;5414.377346605191;5452.509274165344;4355.825766969286;4434.600885920154;4752.263399173134;5326;5414.783408663609;4938.012397520582;5401.9736250112355;5616.747246373841;5643.548516118783;5762.250907382014;3418.777779444328;3735.9726075329504;3779.0160165913117;3791.7724936503896;3811.352070148137;4276.786160692015;4884.900897298079;5397.892042159214;5402;5416.83749487516;4401.933970990502;4726.62187025044;5269.710165940756;5280.20796880471;5317.680939143664;5080.339555857715;5198.376194856642;5297.920531192112;5350.732463376893;5390.27231323782;4080.164285821326;4478.9922267489555;5169.741512924264;5196.948030519647;5252.684838909678;4346.673999450117;4561.74910379919;5244.344456942882;5264.868378290574;5384.9192262116885;5249.372694464728;5275.683458992473;5305.867353316044;5313.555764348443;5325.201219817059 03:17:00 INFO - PROCESS | 1653 | |5;yui.html;680.9795706128816;736.4844608773856;772.3589420780753;791.4183075439553;797.393980574763;14924.5522634321;16738.497845064652;17484.56288592786;17647.029413382257;17718.291268349265;15457;15513.922430387833;15852.524424267262;15930.601734956632;16075.276612552434;825.6160885188372;832.6003518311184;841.6885752271668;842.0526907229367;843.1568431568431;671.4058058618109;687.6286805125227;845.5560830563942;850.2560259772699;851.4720873058712;722.0216606498199;872.7032808844991;886.7605746448455;892.959816808242;902.7743064233941;886.2156991062956;887.1616322575169;888.1695614600337;888.8622263549192;888.9422187557852;517.65317237451;532.2123257578767;640.7693230437039;656.3567703650449;656.5404217048037;14272.14367137975;14701.705965880623;14706.55880323592;14716.411343546246;14720.926395368062;11190.440477976068;13242.73514529704;13387.665308367272;13445.731085378335;13965.511207107702;11052.502637381338;12548.1216314858;12808.679783005406;12915.67710807228;13383.53157639478;3535.4696795480627;3966.861159859391;4449.399331090293;4461.397711308964;4520.11857687751;104050;113022.43488782586;113796;114259.42870285595;115256.42371788171;9309.30180236485;10662.466876656135;10761.085307748928;10811.40537270442;10906.61826836057;20267;20614.896925515575;20681.172753089708;21433.356999290045;21579.460513486974;7636;7725.266099720518;7819.100803407568;7947.920520794718;7950.0857401398425;3327.9500807487907;4161.336406218109;4370.104128653618;4456.376107344974;4547.795349209294;3505.94741078884;3612.1872578670113;3656.634336566322;3661.2677464507206;3670.6879915206973;652.7813182583793;662.9502787290972;671.5467059734659;681.4854784637566;682.7269092363094;508.76732803119734;556.7411153813496;586.7770247305997;643.7264162730821;681.5842336174929;6213.751449942042;7857.489263197988;8193.385496087818;8405.95797021011;9337.859932101024;596.8507873031742;603.8852618002566;663.5255792108666;666.5967089910631;671.3219648155398;4055.3511438169753;4181.829087855405;4358.891027724332;4557.840475583405;4610.703775749427;86455;91774.54112729394;130500.3474982638;161458;163765;128873.35563322123;132182.67817321897;133626.33186834003;136794;137026;61151;61363.079553806725;71752.64123679453;92620.53689731508;94336.03807638401;8556.27271681889;8669.6098675561;8707.91292087071;8756.4308319959;8944.508052056939;49239;64362.67818660877;65174;66388.67222655423;68660;99720.50139749255;100866.49566752119;103358;104799;105532.84219154382;11391;11410.94294528522;11479.655610331703;11575.073994080285;11807.940960295144;13199.538016169388;14263.857361426253;14685.926570367079;15116.395344186109;15434.922825385802;13660;13911.582652520441;13934.163950163023;13935.512257070954;14413.85586144125;13225.338733063501;13327.533536326182;13628.591142265748;13987.732512837052;14060.296985150497;11132.385804058204;13581.185128892299;13823.861761382257;13962;14177.291135443393;10587.36475811423;10984.945075274572;11388;11617.767644647229;11921.642350729491;8985.910140898508;11309;11430.714232144262;11475.54810254055;11484.88515114838 03:17:00 INFO - PROCESS | 1653 | __end_tp_report 03:17:00 INFO - PROCESS | 1653 | __start_cc_report 03:17:00 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1739 03:17:00 INFO - PROCESS | 1653 | __end_cc_report 03:17:00 INFO - PROCESS | 1653 | __startTimestamp1476353820096__endTimestamp 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | ------- Summary: start ------- 03:17:00 INFO - PROCESS | 1653 | Number of tests: 6 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5519.79 Median:5650.80 stddev:611.12 (10.8%) stddev-sans-first:579.89 03:17:00 INFO - PROCESS | 1653 | Values: 2990.7 3280.7 3303.5 3318.5 3347.5 5365.6 5444.7 5474.9 5495.5 5497.7 5389.7 5445.6 5495.9 5502.3 5513.3 4913.5 4983.4 5023.0 5033.9 5049.6 4882.4 5124.3 5135.1 5143.3 5185.0 4456.0 5103.6 5106.6 5125.1 5154.3 4939.5 4990.0 4992.6 5000.5 5065.8 5005.1 5025.4 5046.9 5082.7 5083.0 5492.6 5522.3 5531.9 5544.1 5566.4 5291.0 5399.8 5847.9 5873.1 5976.6 5912.3 5968.4 6007.6 6024.0 6100.4 5554.9 5574.8 5578.4 5578.4 5587.4 6150.3 6177.0 6203.3 6224.7 6236.9 5630.5 5713.3 5715.4 5768.4 5771.4 5882.8 5884.7 5900.0 5912.5 5928.8 5791.9 5822.1 5844.6 5882.0 5882.1 5903.2 5903.3 5917.4 5926.4 5930.3 4761.5 4765.0 4765.4 4766.2 4767.1 4207.5 4213.6 4229.2 4244.4 4342.8 5528.8 5543.2 5545.0 5573.0 5612.7 6058.1 6061.1 6086.3 6087.1 6096.8 5478.3 5545.4 5546.7 5593.2 5600.2 5177.4 5229.0 5235.9 5245.1 5270.3 6221.1 6250.2 6258.4 6272.6 6288.9 5855.7 5958.4 6039.8 6066.8 6080.5 6123.4 6130.7 6150.0 6177.7 6186.6 5897.5 5924.4 5928.7 5936.3 5960.7 6058.6 6060.9 6062.1 6069.5 6076.1 5569.6 5617.9 5745.1 5746.3 5761.1 5706.8 5798.7 5801.4 5818.8 5866.8 5600.5 5675.6 5681.5 5689.8 5693.9 5623.6 5723.9 5770.8 5788.5 5824.9 5650.8 5698.8 5773.7 5780.3 5792.6 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:65455.44 Median:38449.12 stddev:146590.32 (381.3%) stddev-sans-first:146920.45 03:17:00 INFO - PROCESS | 1653 | Values: 510.6 535.6 536.3 550.2 551.8 30371.0 31952.5 39480.4 40312.0 40391.6 42182.6 42487.2 42535.6 42725.6 42730.6 9444.3 9582.3 9587.8 9600.1 9614.7 9288.0 9402.1 9408.6 9412.8 9417.5 9372.7 9450.2 9451.4 9457.2 9465.0 9390.6 9431.4 9438.6 9459.7 9505.5 9442.4 9517.9 9529.2 9544.9 9606.0 39634.6 40012.4 40021.4 40111.4 40200.8 3259.3 3504.2 3510.1 3542.9 3574.8 30208.7 30570.8 30753.4 31508.5 31598.0 3136.1 3151.8 3176.0 3191.2 3239.9 475836.0 478880.6 479157.0 479371.6 479506.0 30049.4 57636.0 57956.7 58039.7 58111.4 34139.3 34192.5 34397.8 34437.8 34442.0 14188.5 14315.3 14317.9 14319.4 14342.9 12213.4 12310.8 12313.2 12330.3 12349.6 7638.0 7643.5 7666.3 7717.9 7735.3 3180.7 3229.2 3231.6 3236.6 3265.9 894.2 947.5 956.5 1014.7 1054.0 19107.6 29801.9 29815.7 30459.5 30481.8 1013.9 1056.5 1070.5 1082.8 1117.5 12769.6 15427.2 15489.4 15676.5 15896.8 679550.0 685632.0 685914.0 686407.0 688118.0 56729.4 57285.0 57383.7 57398.4 57532.0 1223.3 1272.0 1332.1 1367.5 1389.6 29764.3 30676.5 30983.4 31127.5 31179.8 43786.8 44278.0 44307.0 44315.7 44400.3 443618.0 445219.0 445221.0 445999.0 448450.0 21487.9 23134.7 23609.4 26277.7 27053.7 45817.0 45917.5 46505.5 47425.1 47435.8 29906.7 35978.6 37905.1 38136.8 38655.8 36533.3 38218.4 38931.1 38960.0 39325.0 33748.0 37339.3 37405.6 37590.4 38242.4 29033.0 31381.5 32323.5 33969.2 34134.8 24877.6 24882.6 24888.6 25828.0 25843.6 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33467.00 Median:25578.87 stddev:91695.01 (358.5%) stddev-sans-first:91918.56 03:17:00 INFO - PROCESS | 1653 | Values: 366.8 367.2 373.1 387.2 397.4 15388.7 15726.1 19170.0 20949.9 21001.8 19946.3 20201.0 20661.8 20834.3 20858.0 5331.3 5790.0 5849.4 5862.6 5865.8 4912.2 5711.3 5836.1 5836.5 5845.6 5773.5 6141.7 6389.3 6459.0 6481.3 6189.3 6419.3 6433.7 6444.7 6486.2 5624.6 5626.2 5635.2 5636.8 5639.8 23965.9 25090.7 25402.9 25754.9 25915.4 2100.1 2167.7 2319.5 2329.5 2354.2 9469.6 9768.5 9982.4 10264.5 10385.9 1833.3 1851.3 1865.4 1912.5 1917.5 109657.0 117152.0 120017.0 121450.0 123609.4 10642.8 10966.7 11387.9 11448.3 11640.1 11761.7 14265.0 14273.3 14302.6 14307.0 8051.6 8329.1 8369.2 8497.5 8541.6 8239.0 8249.4 8258.7 8445.2 8447.3 3647.3 4359.6 4490.0 4560.8 4612.8 1857.5 1920.1 1926.4 1927.3 1928.7 775.6 831.2 872.7 890.2 918.5 20759.8 21358.4 21372.8 21391.4 21413.7 807.2 958.5 964.3 969.6 973.6 10512.5 11188.3 11189.0 11215.9 11356.3 539452.0 539492.0 544008.0 554883.0 555698.0 89912.5 124474.0 127936.0 130440.0 135952.0 921.7 931.6 963.8 1017.1 1020.1 17871.9 18761.6 19275.9 19286.7 19577.9 53230.1 53796.7 54406.7 56179.0 56540.4 74308.6 80281.0 80716.4 80903.0 82244.0 5472.3 6259.2 7499.3 8234.0 8331.6 10130.5 12847.4 13028.1 13046.8 13346.9 11106.9 11385.8 11466.7 11547.7 11662.5 10991.2 11124.4 11156.2 11577.2 11621.5 11054.7 11509.9 11738.0 11768.1 11900.6 7944.0 8103.9 8290.8 8437.2 8442.0 10127.7 10843.0 11120.0 11294.7 11405.8 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5593.98 Median:4979.67 stddev:3172.41 (63.7%) stddev-sans-first:3166.11 03:17:00 INFO - PROCESS | 1653 | Values: 1462.1 1481.2 1640.9 1834.5 1849.9 8345.3 8843.0 8859.0 8871.5 8882.0 6367.0 8031.5 8057.0 8414.3 8621.1 2315.8 2354.0 2357.0 2361.6 2364.2 1932.9 2300.2 2311.3 2353.1 2358.4 2317.8 2345.6 2347.9 2348.3 2361.6 2320.2 2329.2 2332.6 2336.9 2337.5 2242.5 2271.1 2273.7 2274.4 2274.6 8654.0 8716.3 8782.3 8787.2 8805.7 5097.1 5284.4 5285.5 5297.0 5303.3 3116.9 3387.4 3620.5 3624.6 3664.6 991.2 1050.2 1068.7 1072.6 1072.8 11515.9 11528.4 11661.2 11661.3 11663.2 4518.1 4533.8 4717.1 4723.7 4724.5 9863.9 9890.1 9900.4 9906.9 9921.4 5302.2 5305.0 5318.7 5322.0 5325.7 4320.3 4343.2 4371.5 4848.4 4885.3 3816.5 3830.6 3902.2 3946.9 3960.7 1198.6 1216.3 1227.8 1233.8 1258.0 806.0 869.5 884.2 921.7 964.2 5553.9 5777.7 5984.5 6078.7 6079.1 404.1 413.9 420.8 424.0 427.4 3548.0 3833.1 3914.7 3947.4 4054.5 8779.0 10741.5 10745.6 10746.9 10759.8 10687.5 10703.3 10703.6 10707.6 10715.6 7557.7 7972.0 8055.7 8138.0 8338.7 4620.1 5018.4 5258.8 5921.6 6099.2 8696.5 9733.5 9975.3 10066.9 10126.0 8926.0 9284.9 10204.9 10234.8 10243.8 6711.0 6940.4 6956.7 7426.5 7529.9 7982.5 8103.1 8181.1 8194.6 8284.2 7325.8 7411.6 7434.7 7537.8 7580.4 7038.2 7264.5 7318.8 7340.9 7377.3 6849.5 6850.5 6937.2 6939.4 6959.2 4264.7 4975.2 4984.1 5020.8 5162.2 4988.3 5017.9 5065.6 5128.9 5133.2 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4256.22 Median:4744.43 stddev:1307.78 (27.6%) stddev-sans-first:1277.17 03:17:00 INFO - PROCESS | 1653 | Values: 293.6 293.8 302.8 307.4 308.5 4168.9 4397.9 5037.9 5088.7 5093.4 4175.7 4658.7 4956.3 4969.4 5084.2 3344.4 3739.7 3760.7 3838.6 3902.0 2817.4 3724.9 3925.5 3952.0 3977.4 3150.7 3728.8 3746.9 3880.6 3914.2 3895.3 3899.7 3907.5 3919.3 3986.0 2813.5 3415.9 3435.7 3443.6 3445.2 3735.1 3813.3 4757.8 4764.2 4924.4 3640.4 4258.3 4275.8 4344.1 4483.8 4318.3 4811.7 5306.4 5324.4 5343.9 3303.9 3412.3 3725.9 3731.3 3744.9 4432.0 5493.9 5567.1 5577.7 5597.8 3286.0 3611.3 3789.1 3793.7 3802.4 3022.5 3189.9 3214.7 3849.5 3864.0 3639.3 4208.5 4399.4 4416.3 4433.7 4836.4 5087.7 5331.8 5338.6 5365.2 1255.8 1285.7 1309.3 1309.3 1317.5 805.9 841.1 863.9 871.4 885.5 4509.4 4978.4 4980.3 5005.3 5015.1 4915.5 5372.7 5470.5 5494.4 5501.0 4322.5 4619.5 4987.6 4992.0 5052.1 2630.5 2803.5 2847.5 2859.1 2873.9 5119.9 5326.3 5461.9 5592.7 5625.7 5077.3 5334.8 5417.0 5515.6 5620.6 4736.6 5565.2 5602.9 5612.0 5616.0 5110.3 5359.9 5384.5 5414.4 5452.5 4355.8 4434.6 4752.3 5326.0 5414.8 4938.0 5402.0 5616.7 5643.5 5762.3 3418.8 3736.0 3779.0 3791.8 3811.4 4276.8 4884.9 5397.9 5402.0 5416.8 4401.9 4726.6 5269.7 5280.2 5317.7 5080.3 5198.4 5297.9 5350.7 5390.3 4080.2 4479.0 5169.7 5196.9 5252.7 4346.7 4561.7 5244.3 5264.9 5384.9 5249.4 5275.7 5305.9 5313.6 5325.2 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:23763.42 Median:3665.98 stddev:37725.05 (1029.1%) stddev-sans-first:37791.07 03:17:00 INFO - PROCESS | 1653 | Values: 681.0 736.5 772.4 791.4 797.4 14924.6 16738.5 17484.6 17647.0 17718.3 15457.0 15513.9 15852.5 15930.6 16075.3 825.6 832.6 841.7 842.1 843.2 671.4 687.6 845.6 850.3 851.5 722.0 872.7 886.8 893.0 902.8 886.2 887.2 888.2 888.9 888.9 517.7 532.2 640.8 656.4 656.5 14272.1 14701.7 14706.6 14716.4 14720.9 11190.4 13242.7 13387.7 13445.7 13965.5 11052.5 12548.1 12808.7 12915.7 13383.5 3535.5 3966.9 4449.4 4461.4 4520.1 104050.0 113022.4 113796.0 114259.4 115256.4 9309.3 10662.5 10761.1 10811.4 10906.6 20267.0 20614.9 20681.2 21433.4 21579.5 7636.0 7725.3 7819.1 7947.9 7950.1 3328.0 4161.3 4370.1 4456.4 4547.8 3505.9 3612.2 3656.6 3661.3 3670.7 652.8 663.0 671.5 681.5 682.7 508.8 556.7 586.8 643.7 681.6 6213.8 7857.5 8193.4 8406.0 9337.9 596.9 603.9 663.5 666.6 671.3 4055.4 4181.8 4358.9 4557.8 4610.7 86455.0 91774.5 130500.3 161458.0 163765.0 128873.4 132182.7 133626.3 136794.0 137026.0 61151.0 61363.1 71752.6 92620.5 94336.0 8556.3 8669.6 8707.9 8756.4 8944.5 49239.0 64362.7 65174.0 66388.7 68660.0 99720.5 100866.5 103358.0 104799.0 105532.8 11391.0 11410.9 11479.7 11575.1 11807.9 13199.5 14263.9 14685.9 15116.4 15434.9 13660.0 13911.6 13934.2 13935.5 14413.9 13225.3 13327.5 13628.6 13987.7 14060.3 11132.4 13581.2 13823.9 13962.0 14177.3 10587.4 10984.9 11388.0 11617.8 11921.6 8985.9 11309.0 11430.7 11475.5 11484.9 03:17:00 INFO - PROCESS | 1653 | -------- Summary: end -------- 03:17:00 INFO - PROCESS | 1653 | 03:17:00 INFO - TEST-INFO | 1653: exit 0 03:17:00 INFO - TEST-OK | dromaeo_css | took 1120208ms 03:17:00 INFO - TEST-START | kraken 03:17:00 INFO - operating with platform_type : mac_ 03:17:00 INFO - Initialising browser for kraken test... 03:17:00 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpziyeAm/profile http://localhost:49274/getInfo.html) 03:17:03 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 03:17:03 INFO - PROCESS | 1660 | colorDepth:24 03:17:03 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 03:17:03 INFO - PROCESS | 1660 | __metrics 03:17:03 INFO - PROCESS | 1660 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:17:03 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 181: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:17:03 INFO - TEST-INFO | 1660: exit 0 03:17:03 INFO - Browser initialized. 03:17:03 INFO - Running cycle 1/1 for kraken test... 03:17:03 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/T/tmpziyeAm/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:17:15 INFO - PROCESS | 1661 | RSS: Main: 235433984 03:17:15 INFO - PROCESS | 1661 | 03:17:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 03:17:19 INFO - PROCESS | 1661 | RSS: Main: 293699584 03:17:19 INFO - PROCESS | 1661 | 03:17:22 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 03:17:22 INFO - PROCESS | 1661 | RSS: Main: 293732352 03:17:22 INFO - PROCESS | 1661 | 03:17:26 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 03:17:26 INFO - PROCESS | 1661 | RSS: Main: 382357504 03:17:26 INFO - PROCESS | 1661 | 03:17:29 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 03:17:29 INFO - PROCESS | 1661 | RSS: Main: 284254208 03:17:29 INFO - PROCESS | 1661 | 03:17:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 03:17:32 INFO - PROCESS | 1661 | RSS: Main: 289591296 03:17:32 INFO - PROCESS | 1661 | 03:17:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 03:17:35 INFO - PROCESS | 1661 | RSS: Main: 326606848 03:17:35 INFO - PROCESS | 1661 | 03:17:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 03:17:39 INFO - PROCESS | 1661 | RSS: Main: 328171520 03:17:39 INFO - PROCESS | 1661 | 03:17:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 03:17:42 INFO - PROCESS | 1661 | RSS: Main: 326795264 03:17:42 INFO - PROCESS | 1661 | 03:17:44 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 03:17:44 INFO - PROCESS | 1661 | RSS: Main: 300900352 03:17:44 INFO - PROCESS | 1661 | 03:17:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 03:17:47 INFO - PROCESS | 1661 | RSS: Main: 305451008 03:17:47 INFO - PROCESS | 1661 | 03:17:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 03:17:50 INFO - PROCESS | 1661 | RSS: Main: 311996416 03:17:50 INFO - PROCESS | 1661 | 03:17:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 03:17:53 INFO - PROCESS | 1661 | RSS: Main: 318234624 03:17:53 INFO - PROCESS | 1661 | 03:17:56 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 03:17:57 INFO - PROCESS | 1661 | RSS: Main: 318377984 03:17:57 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 03:17:59 INFO - PROCESS | 1661 | RSS: Main: 362594304 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | __start_tp_report 03:17:59 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 03:17:59 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 03:17:59 INFO - PROCESS | 1661 | |i|pagename|runs| 03:17:59 INFO - PROCESS | 1661 | |0;ai-astar;109;110;112;110;111;111;112;111;110;112 03:17:59 INFO - PROCESS | 1661 | |1;audio-beat-detection;149;111;113;152;113;115;123;141;178;114 03:17:59 INFO - PROCESS | 1661 | |2;audio-dft;184;182;181;184;183;184;181;185;180;182 03:17:59 INFO - PROCESS | 1661 | |3;audio-fft;79;58;59;84;61;58;58;58;85;73 03:17:59 INFO - PROCESS | 1661 | |4;audio-oscillator;88;71;90;70;67;69;92;68;68;92 03:17:59 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;90;86;90;85;90;86;91;88;90;87 03:17:59 INFO - PROCESS | 1661 | |6;imaging-darkroom;96;98;96;96;98;125;168;96;97;97 03:17:59 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;86;84;84;83;86;82;85;83;86 03:17:59 INFO - PROCESS | 1661 | |8;json-parse-financial;54;53;53;52;54;55;54;53;54;54 03:17:59 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;64;74;79;73;72;67;70;70;69;71 03:17:59 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;86;85;87;88;85;88;87;91;87;91 03:17:59 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;145;146;146;145;143;148;140;147;180;154 03:17:59 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;173;177;175;172;233;166;227;239;185;172 03:17:59 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;70;68;68;70;68;69;67;68;67;69 03:17:59 INFO - PROCESS | 1661 | __end_tp_report 03:17:59 INFO - PROCESS | 1661 | __start_cc_report 03:17:59 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1998 03:17:59 INFO - PROCESS | 1661 | __end_cc_report 03:17:59 INFO - PROCESS | 1661 | __startTimestamp1476353879511__endTimestamp 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | ------- Summary: start ------- 03:17:59 INFO - PROCESS | 1661 | Number of tests: 14 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:110.80 Median:111.00 stddev:1.03 (0.9%) stddev-sans-first:0.87 03:17:59 INFO - PROCESS | 1661 | Values: 109.0 110.0 112.0 110.0 111.0 111.0 112.0 111.0 110.0 112.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:130.90 Median:132.00 stddev:22.93 (17.4%) stddev-sans-first:23.36 03:17:59 INFO - PROCESS | 1661 | Values: 149.0 111.0 113.0 152.0 113.0 115.0 123.0 141.0 178.0 114.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:182.60 Median:183.50 stddev:1.65 (0.9%) stddev-sans-first:1.67 03:17:59 INFO - PROCESS | 1661 | Values: 184.0 182.0 181.0 184.0 183.0 184.0 181.0 185.0 180.0 182.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:67.30 Median:67.00 stddev:11.62 (17.3%) stddev-sans-first:11.53 03:17:59 INFO - PROCESS | 1661 | Values: 79.0 58.0 59.0 84.0 61.0 58.0 58.0 58.0 85.0 73.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:77.50 Median:79.50 stddev:11.30 (14.2%) stddev-sans-first:11.32 03:17:59 INFO - PROCESS | 1661 | Values: 88.0 71.0 90.0 70.0 67.0 69.0 92.0 68.0 68.0 92.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:88.30 Median:90.00 stddev:2.16 (2.4%) stddev-sans-first:2.20 03:17:59 INFO - PROCESS | 1661 | Values: 90.0 86.0 90.0 85.0 90.0 86.0 91.0 88.0 90.0 87.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:106.70 Median:96.50 stddev:23.31 (24.2%) stddev-sans-first:24.40 03:17:59 INFO - PROCESS | 1661 | Values: 96.0 98.0 96.0 96.0 98.0 125.0 168.0 96.0 97.0 97.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.40 Median:85.00 stddev:1.43 (1.7%) stddev-sans-first:1.50 03:17:59 INFO - PROCESS | 1661 | Values: 85.0 86.0 84.0 84.0 83.0 86.0 82.0 85.0 83.0 86.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:53.60 Median:54.00 stddev:0.84 (1.6%) stddev-sans-first:0.88 03:17:59 INFO - PROCESS | 1661 | Values: 54.0 53.0 53.0 52.0 54.0 55.0 54.0 53.0 54.0 54.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.50 stddev:4.07 (5.7%) stddev-sans-first:3.46 03:17:59 INFO - PROCESS | 1661 | Values: 64.0 74.0 79.0 73.0 72.0 67.0 70.0 70.0 69.0 71.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:87.50 Median:87.50 stddev:2.12 (2.4%) stddev-sans-first:2.18 03:17:59 INFO - PROCESS | 1661 | Values: 86.0 85.0 87.0 88.0 85.0 88.0 87.0 91.0 87.0 91.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:149.40 Median:146.50 stddev:11.34 (7.7%) stddev-sans-first:11.91 03:17:59 INFO - PROCESS | 1661 | Values: 145.0 146.0 146.0 145.0 143.0 148.0 140.0 147.0 180.0 154.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.90 Median:181.00 stddev:28.90 (16.0%) stddev-sans-first:29.83 03:17:59 INFO - PROCESS | 1661 | Values: 173.0 177.0 175.0 172.0 233.0 166.0 227.0 239.0 185.0 172.0 03:17:59 INFO - PROCESS | 1661 | 03:17:59 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.40 Median:68.50 stddev:1.07 (1.6%) stddev-sans-first:0.97 03:17:59 INFO - PROCESS | 1661 | Values: 70.0 68.0 68.0 70.0 68.0 69.0 67.0 68.0 67.0 69.0 03:17:59 INFO - PROCESS | 1661 | -------- Summary: end -------- 03:17:59 INFO - PROCESS | 1661 | 03:18:00 INFO - TEST-INFO | 1661: exit 0 03:18:00 INFO - TEST-OK | kraken | took 59634ms 03:18:00 INFO - SUITE-END | took 1179s 03:18:00 INFO - Completed test suite (00:19:40) 03:18:00 INFO - javascript benchmark 03:18:00 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2990.67102618712, 3280.671932806719, 3303.5375047493353, 3318.5022246662984, 3347.548081009063, 5365.597580181482, 5444.700541470223, 5474.863128421792, 5495.532879705225, 5497.725113744316, 5389.676619402839, 5445.646033007852, 5495.862603434916, 5502.339719233688, 5513.283273174492, 4913.533214344649, 4983.401991760994, 5023.0, 5033.874153146164, 5049.570786483135, 4882.43851957026, 5124.308218390507, 5135.05001574708, 5143.279940808271, 5185.014847179043, 4455.952851079994, 5103.617228707862, 5106.64253502255, 5125.051865404912, 5154.252633368178, 4939.53074457926, 4990.001999600058, 4992.575631071363, 5000.499950004971, 5065.848024559269, 5005.074061298671, 5025.422076461217, 5046.949530504721, 5082.669626474304, 5082.974585127087, 5492.642978206443, 5522.337319521683, 5531.944680553183, 5544.085225937715, 5566.443355664401, 5290.973545132289, 5399.8390346075785, 5847.883042339172, 5873.089671100986, 5976.551758618078, 5912.320083190445, 5968.432998865101, 6007.60950538218, 6024.0, 6100.420460056288, 5554.888902221933, 5574.804881829116, 5578.386377498472, 5578.44215578447, 5587.413321601254, 6150.292716337589, 6177.0, 6203.286622038433, 6224.688765561704, 6236.937630623635, 5630.5495560355075, 5713.314402271671, 5715.371309155989, 5768.423157684198, 5771.365149833515, 5882.764689412375, 5884.676342801187, 5899.970500147472, 5912.497437717842, 5928.79249226275, 5791.913121303184, 5822.1266809978, 5844.649321040752, 5881.9705901470215, 5882.088276317178, 5903.2325797646035, 5903.26209223847, 5917.437843404869, 5926.377730338339, 5930.347661757203, 4761.500042495557, 4764.99935013651, 4765.404324459442, 4766.23740201573, 4767.118083154627, 4207.474065741782, 4213.620774130343, 4229.154169166179, 4244.427002354642, 4342.761148136819, 5528.778848846001, 5543.168524721244, 5544.972275138679, 5573.0, 5612.663240205601, 6058.091286307089, 6061.121137435047, 6086.3000754913755, 6087.05650624154, 6096.847578810565, 5478.342598888159, 5545.390007099216, 5546.69493177871, 5593.188987596775, 5600.159976003632, 5177.430482646906, 5229.032628963578, 5235.895282094337, 5245.10833158364, 5270.341207349082, 6221.097940798559, 6250.249970003629, 6258.405451482284, 6272.6236425814595, 6288.874222515616, 5855.677937713466, 5958.433948774859, 6039.849003774941, 6066.757329706938, 6080.452759251689, 6123.404636889863, 6130.696388836325, 6150.015997440388, 6177.660228687465, 6186.566940314135, 5897.469227769521, 5924.407559244041, 5928.733207005609, 5936.347001829881, 5960.672163031075, 6058.57589968698, 6060.90908636362, 6062.060380641008, 6069.453749162597, 6076.118962750465, 5569.637973531708, 5617.887642247214, 5745.080787074048, 5746.281714785652, 5761.135829625589, 5706.828795135986, 5798.6810725410505, 5801.361850196559, 5818.8254352369495, 5866.823995280149, 5600.467955544217, 5675.602707810413, 5681.488666020078, 5689.800856969986, 5693.943060569507, 5623.606347555632, 5723.914141287801, 5770.769169233184, 5788.507976822018, 5824.854378640568, 5650.802221922213, 5698.8290351289525, 5773.6824474654295, 5780.306363236438, 5792.594518383673], "value": 5480.599797460918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [510.63999880084543, 535.6170338208183, 536.3483367708234, 550.1967127993131, 551.771015028763, 30371.0, 31952.520712189307, 39480.40779388312, 40312.0, 40391.5862944797, 42182.57817421825, 42487.15025699484, 42535.574644253546, 42725.57274427255, 42730.57269427305, 9444.33889627728, 9582.281328900326, 9587.760305992371, 9600.087991640783, 9614.663486777965, 9288.0, 9402.106799854002, 9408.57661405235, 9412.764680882965, 9417.529123543829, 9372.718818435424, 9450.19673327768, 9451.432914025147, 9457.243420526376, 9465.0, 9390.577424015935, 9431.386959847656, 9438.622455101788, 9459.668911588062, 9505.524723763785, 9442.38624489406, 9517.90482095184, 9529.190018848407, 9544.90455095447, 9605.951970240174, 39634.60365396338, 40012.39981400281, 40021.39967900484, 40111.39832902509, 40200.79899600513, 3259.315543735818, 3504.1940353718787, 3510.1049232445816, 3542.9114272143147, 3574.767640103385, 30208.69791302081, 30570.84714576435, 30753.38493230123, 31508.527372089437, 31598.0, 3136.1218858719594, 3151.8266495342746, 3175.984120079408, 3191.186247506871, 3239.8704051838135, 475836.0, 478880.60559697327, 479157.0, 479371.60314198554, 479506.0, 30049.399012020076, 57636.0, 57956.71021644865, 58039.709801451565, 58111.41888581144, 34139.31721365559, 34192.487112692834, 34397.828010860285, 34437.82781086129, 34442.0, 14188.503402380868, 14315.284235788376, 14317.85682143186, 14319.35562899672, 14342.928285358506, 12213.389330533615, 12310.81533776994, 12313.199642023419, 12330.260184388966, 12349.567765128177, 7638.0, 7643.503172293783, 7666.348360389318, 7717.922820771832, 7735.265149810759, 3180.6978337057944, 3229.1765599771907, 3231.628362738268, 3236.6116066072223, 3265.869365225364, 894.2130924786146, 947.4646824544249, 956.5360800011989, 1014.7361685961703, 1053.9683809485728, 19107.61784764297, 29801.85099074534, 29815.701842981292, 30459.543106853416, 30481.847590761903, 1013.9340942838692, 1056.487603512296, 1070.4808168038496, 1082.783443311325, 1117.4580328540815, 12769.553065642658, 15427.228638568251, 15489.380424783109, 15676.529704108667, 15896.841031589536, 679550.0, 685632.0, 685914.0, 686407.0, 688118.0, 56729.43270567324, 57285.0, 57383.71308143516, 57398.426015740144, 57532.0, 1223.3332833605707, 1272.0141890035297, 1332.060897067565, 1367.4940272099188, 1389.6248013036222, 29764.255893601967, 30676.539851901794, 30983.380332393677, 31127.53308700326, 31179.84410077935, 43786.78106609447, 44278.0, 44307.0, 44315.670529884155, 44400.333994990746, 443618.0, 445219.0, 445221.0, 445999.0, 448450.0, 21487.892560537097, 23134.652980204974, 23609.40976475602, 26277.73722262753, 27053.72946270591, 45817.0, 45917.54082459133, 46505.53494465012, 47425.05149897052, 47435.81410464766, 29906.700932990392, 35978.64021359753, 37905.05237369088, 38136.809315953244, 38655.80672096622, 36533.26933461369, 38218.42672359861, 38931.11178491015, 38960.02599935024, 39325.0, 33748.0, 37339.25321493501, 37405.62594374021, 37590.43614345732, 38242.426363605126, 29033.0, 31381.52927706132, 32323.51514727234, 33969.15077122993, 34134.829325853214, 24877.62683559712, 24882.626760598967, 24888.62667060032, 25827.966881325287, 25843.612345815203], "value": 18156.10783032439, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [366.82942431769237, 367.2214904402666, 373.09524403321956, 387.1753165756937, 397.4435789894148, 15388.690372073259, 15726.056436613811, 19170.0, 20949.895250523747, 21001.789982100174, 19946.301879434224, 20201.0, 20661.793382066175, 20834.270800521947, 20858.0, 5331.333583302077, 5789.9710501447435, 5849.444302791228, 5862.618929769551, 5865.8240252792275, 4912.23159662523, 5711.314642242936, 5836.124581312794, 5836.533077353823, 5845.561582881301, 5773.5381169506545, 6141.740943106653, 6389.2971773105155, 6459.0, 6481.28705842357, 6189.319174890758, 6419.26178489475, 6433.678316084177, 6444.709988050526, 6486.156799616019, 5624.606277560572, 5626.212330273765, 5635.154726791015, 5636.802711905105, 5639.774409023676, 23965.88017059921, 25090.749092509024, 25402.87298563514, 25754.87122564375, 25915.352116197057, 2100.0759665747173, 2167.6856855755827, 2319.52449747803, 2329.5108027314286, 2354.1642716835618, 9469.573869175903, 9768.462734549597, 9982.35114717541, 10264.486775661187, 10385.896141038644, 1833.2575307000686, 1851.3150134450204, 1865.3844231403605, 1912.4740696308488, 1917.4726950088698, 109657.0, 117152.0, 120017.0, 121450.0, 123609.38195309056, 10642.840357394645, 10966.670999869857, 11387.886121138848, 11448.255863368855, 11640.126990475748, 11761.705957351134, 14265.0, 14273.286335683382, 14302.570922872123, 14306.9985101044, 8051.5571643559, 8329.125441828643, 8369.204925532065, 8497.490150590984, 8541.572921353907, 8239.0, 8249.422540422232, 8258.669653213925, 8445.197706218023, 8447.324214062983, 3647.3434781739543, 4359.58043285973, 4490.0, 4560.8175672973375, 4612.76936153191, 1857.535616095976, 1920.0879582198345, 1926.4317026476942, 1927.3447028010264, 1928.6817675083735, 775.6470805783355, 831.1854382704983, 872.7425409504211, 890.2343984173606, 918.4672889723944, 20759.792402076087, 21358.359249222547, 21372.824494652632, 21391.358259252247, 21413.67879481809, 807.168616325186, 958.5351104714208, 964.3394274921701, 969.5976169889558, 973.5862258540234, 10512.526936287886, 11188.328700278009, 11189.04893084097, 11215.887841121648, 11356.26184298018, 539452.0, 539492.0, 544008.0, 554883.0, 555698.0, 89912.46291526264, 124474.0, 127936.0, 130440.0, 135951.96072058927, 921.7373048681094, 931.5714771205186, 963.797602503468, 1017.064300843226, 1020.1226944827442, 17871.92768433898, 18761.6247675043, 19275.903620481808, 19286.7106993398, 19577.902110489358, 53230.080089589654, 53796.73101634467, 54406.72796635992, 56179.0, 56540.43459565352, 74308.62845685738, 80281.0, 80716.36360001304, 80903.0, 82244.0, 5472.315960504937, 6259.186305780219, 7499.287567681062, 8234.0, 8331.583420829056, 10130.544125514221, 12847.421866016239, 13028.064112495027, 13046.804297935349, 13346.86653133495, 11106.888931110909, 11385.772284554097, 11466.713332166762, 11547.653570392902, 11662.53349865996, 10991.175661825555, 11124.443777810915, 11156.163287753614, 11577.247478913843, 11621.477033533341, 11054.723631908944, 11509.942450287695, 11738.0, 11768.117391195523, 11900.642980710592, 7943.960280198562, 8103.87844182326, 8290.751277461686, 8437.156284371515, 8441.986961564651, 10127.7468063299, 10843.0, 11120.0, 11294.717632058935, 11405.828912566152], "value": 9475.453051567616, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1462.115420170797, 1481.1557412275004, 1640.8523232909042, 1834.4955137337222, 1849.9075046247692, 8345.290650294724, 8842.955785221073, 8859.025507194203, 8871.467711937288, 8882.0, 6367.0, 8031.518108913469, 8057.033156021271, 8414.326853851693, 8621.137886211376, 2315.849469784467, 2354.0, 2357.033616217351, 2361.551305252005, 2364.2316247219637, 1932.8646994710305, 2300.2409204962323, 2311.2603966730653, 2353.0822979038103, 2358.41039740065, 2317.8261630377706, 2345.6012477878803, 2347.9178228762, 2348.342464110052, 2361.551305252005, 2320.1879342230254, 2329.1615018593293, 2332.615118505453, 2336.8714720690355, 2337.4623836517526, 2242.5178586603956, 2271.057511132878, 2273.693051438051, 2274.363178310076, 2274.6360582306916, 8654.00478944923, 8716.346274029473, 8782.297416206751, 8787.209151176425, 8805.735827925173, 5097.108006098918, 5284.365876094855, 5285.524302812765, 5297.020051290524, 5303.28405665238, 3116.9064928051966, 3387.3733359328357, 3620.475031120499, 3624.5650521937273, 3664.633536646341, 991.1674193677345, 1050.1913526584563, 1068.6526878764355, 1072.624581396513, 1072.8068947589431, 11515.942420287929, 11528.365939873303, 11661.183717139804, 11661.300321980709, 11663.24188927717, 4518.118966801508, 4533.818647254073, 4717.056588682277, 4723.6929599575915, 4724.511778789671, 9863.852042219372, 9890.109890109925, 9900.356476828983, 9906.851397229048, 9921.404715716935, 5302.2046692996355, 5305.0, 5318.73406329682, 5322.0, 5325.707086110301, 4320.308750599889, 4343.2399330117105, 4371.519132895379, 4848.442429120696, 4885.291632713237, 3816.5420149581673, 3830.559485659129, 3902.23906338267, 3946.881593552198, 3960.702947278908, 1198.6404078776507, 1216.2937464142212, 1227.8403807505147, 1233.7902556565225, 1257.9811302830465, 805.9890573020351, 869.5000374784524, 884.1732979663906, 921.6589861751152, 964.19008033251, 5553.861153471114, 5777.682227477445, 5984.461398474158, 6078.696065196723, 6079.05774604937, 404.1069236986293, 413.9006638406761, 420.8064290426438, 423.9576042395736, 427.36322878910823, 3547.9822600886832, 3833.0800607853917, 3914.745541539791, 3947.401302472503, 4054.4729185205733, 8778.956105219433, 10741.462926853626, 10745.570177192825, 10746.92530746919, 10759.83860242097, 10687.465626718633, 10703.304285221435, 10703.625373111903, 10707.571697132184, 10715.571377144826, 7557.715188417872, 7972.0, 8055.677772889136, 8138.023437187576, 8338.666453341799, 4620.075984802986, 5018.44797072329, 5258.842234732964, 5921.5854890159, 6099.176611157614, 8696.521691307038, 9733.513324333613, 9975.301728878909, 10066.899331006889, 10126.038026387776, 8925.973513046083, 9284.860727088964, 10204.948975255076, 10234.795304094025, 10243.795124097625, 6711.0, 6940.41006514452, 6956.721731130698, 7426.480146389701, 7529.853991612365, 7982.521048737094, 8103.1491693371345, 8181.100078991425, 8194.631241594023, 8284.171582841667, 7325.7802265932105, 7411.592362420119, 7434.73978410753, 7537.773866784005, 7580.3556697681315, 7038.155421349471, 7264.527805692613, 7318.81702957409, 7340.889886651597, 7377.299156580117, 6849.452043836382, 6850.451963842781, 6937.167539895245, 6939.4448444125355, 6959.19969203548, 4264.739687965383, 4975.228839529878, 4984.102861484823, 5020.849374518771, 5162.225666150108, 4988.27669987857, 5017.899642007213, 5065.645404821627, 5128.897422051464, 5133.230015497706], "value": 4412.040090048992, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.59923704143836, 293.79727987688494, 302.8379816798014, 307.3514883595613, 308.52641195764505, 4168.916621667565, 4397.868063958086, 5037.899242015162, 5088.720120393377, 5093.3887933447995, 4175.70336315027, 4658.743769092698, 4956.306117143594, 4969.353983982086, 5084.220522817012, 3344.3812894614452, 3739.7195210359196, 3760.6615404613513, 3838.5777564467885, 3902.024493876531, 2817.4365126974585, 3724.8510059597584, 3925.509311336083, 3951.9844879111715, 3977.4232736253225, 3150.7321877640316, 3728.8322025508783, 3746.906327341811, 3880.553736320331, 3914.2367238388524, 3895.2793733159315, 3899.688024958025, 3907.538250901942, 3919.2749341371937, 3985.9800700996598, 2813.4513769814894, 3415.8633654653786, 3435.7423193260356, 3443.6384179661277, 3445.2248244145117, 3735.0662334416397, 3813.2649644412004, 4757.809687612491, 4764.190087685102, 4924.433690125646, 3640.381135207021, 4258.2966813274625, 4275.819332140631, 4344.103906269122, 4483.825344707814, 4318.330658747899, 4811.735354555498, 5306.389765177016, 5324.440933701944, 5343.946560534384, 3303.852495877699, 3412.283420481702, 3725.9068523286865, 3731.3022574728434, 3744.9438258426144, 4431.9778401108115, 5493.862653433696, 5567.137093750475, 5577.69322687252, 5597.776088956478, 3285.983570082134, 3611.3138503684218, 3789.1474418256225, 3793.696504279651, 3802.421994872097, 3022.501287287617, 3189.8564564594185, 3214.72674822638, 3849.480320156743, 3863.9806800965816, 3639.29033838399, 4208.473940757405, 4399.38408622793, 4416.271315232951, 4433.7118087324225, 4836.371271734652, 5087.669301495391, 5331.840604555161, 5338.626296159309, 5365.195220716923, 1255.79907214847, 1285.7299967007034, 1309.2864388908067, 1309.299524754252, 1317.5322760419913, 805.9113497515268, 841.1252297610539, 863.8920134983127, 871.4161511787117, 885.4908427653996, 4509.421702404198, 4978.4025916890205, 4980.2778597103215, 5005.299258103868, 5015.072211640785, 4915.48387419323, 5372.650777699437, 5470.53500452466, 5494.36814766307, 5500.972495137498, 4322.514554131387, 4619.5122682374595, 4987.60099192071, 4991.975040124848, 5052.120291433847, 2630.473905218964, 2803.453326601293, 2847.547750647185, 2859.070801989362, 2873.8504598160903, 5119.8720031998755, 5326.334208223972, 5461.907618476241, 5592.692401917852, 5625.746841392147, 5077.31894376574, 5334.759935802898, 5417.024935511646, 5515.558755299567, 5620.634658747168, 4736.5973892219545, 5565.193047008162, 5602.887942241132, 5611.9719401403545, 5615.999999999918, 5110.310108135354, 5359.892802144013, 5384.461553844584, 5414.377346605191, 5452.509274165344, 4355.825766969286, 4434.600885920154, 4752.263399173134, 5326.0, 5414.783408663609, 4938.012397520582, 5401.9736250112355, 5616.747246373841, 5643.548516118783, 5762.250907382014, 3418.777779444328, 3735.9726075329504, 3779.0160165913117, 3791.7724936503896, 3811.352070148137, 4276.786160692015, 4884.900897298079, 5397.892042159214, 5402.0, 5416.83749487516, 4401.933970990502, 4726.62187025044, 5269.710165940756, 5280.20796880471, 5317.680939143664, 5080.339555857715, 5198.376194856642, 5297.920531192112, 5350.732463376893, 5390.27231323782, 4080.164285821326, 4478.9922267489555, 5169.741512924264, 5196.948030519647, 5252.684838909678, 4346.673999450117, 4561.74910379919, 5244.344456942882, 5264.868378290574, 5384.9192262116885, 5249.372694464728, 5275.683458992473, 5305.867353316044, 5313.555764348443, 5325.201219817059], "value": 3851.2148490128025, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [680.9795706128816, 736.4844608773856, 772.3589420780753, 791.4183075439553, 797.393980574763, 14924.5522634321, 16738.497845064652, 17484.56288592786, 17647.029413382257, 17718.291268349265, 15457.0, 15513.922430387833, 15852.524424267262, 15930.601734956632, 16075.276612552434, 825.6160885188372, 832.6003518311184, 841.6885752271668, 842.0526907229367, 843.1568431568431, 671.4058058618109, 687.6286805125227, 845.5560830563942, 850.2560259772699, 851.4720873058712, 722.0216606498199, 872.7032808844991, 886.7605746448455, 892.959816808242, 902.7743064233941, 886.2156991062956, 887.1616322575169, 888.1695614600337, 888.8622263549192, 888.9422187557852, 517.65317237451, 532.2123257578767, 640.7693230437039, 656.3567703650449, 656.5404217048037, 14272.14367137975, 14701.705965880623, 14706.55880323592, 14716.411343546246, 14720.926395368062, 11190.440477976068, 13242.73514529704, 13387.665308367272, 13445.731085378335, 13965.511207107702, 11052.502637381338, 12548.1216314858, 12808.679783005406, 12915.67710807228, 13383.53157639478, 3535.4696795480627, 3966.861159859391, 4449.399331090293, 4461.397711308964, 4520.11857687751, 104050.0, 113022.43488782586, 113796.0, 114259.42870285595, 115256.42371788171, 9309.30180236485, 10662.466876656135, 10761.085307748928, 10811.40537270442, 10906.61826836057, 20267.0, 20614.896925515575, 20681.172753089708, 21433.356999290045, 21579.460513486974, 7636.0, 7725.266099720518, 7819.100803407568, 7947.920520794718, 7950.0857401398425, 3327.9500807487907, 4161.336406218109, 4370.104128653618, 4456.376107344974, 4547.795349209294, 3505.94741078884, 3612.1872578670113, 3656.634336566322, 3661.2677464507206, 3670.6879915206973, 652.7813182583793, 662.9502787290972, 671.5467059734659, 681.4854784637566, 682.7269092363094, 508.76732803119734, 556.7411153813496, 586.7770247305997, 643.7264162730821, 681.5842336174929, 6213.751449942042, 7857.489263197988, 8193.385496087818, 8405.95797021011, 9337.859932101024, 596.8507873031742, 603.8852618002566, 663.5255792108666, 666.5967089910631, 671.3219648155398, 4055.3511438169753, 4181.829087855405, 4358.891027724332, 4557.840475583405, 4610.703775749427, 86455.0, 91774.54112729394, 130500.3474982638, 161458.0, 163765.0, 128873.35563322123, 132182.67817321897, 133626.33186834003, 136794.0, 137026.0, 61151.0, 61363.079553806725, 71752.64123679453, 92620.53689731508, 94336.03807638401, 8556.27271681889, 8669.6098675561, 8707.91292087071, 8756.4308319959, 8944.508052056939, 49239.0, 64362.67818660877, 65174.0, 66388.67222655423, 68660.0, 99720.50139749255, 100866.49566752119, 103358.0, 104799.0, 105532.84219154382, 11391.0, 11410.94294528522, 11479.655610331703, 11575.073994080285, 11807.940960295144, 13199.538016169388, 14263.857361426253, 14685.926570367079, 15116.395344186109, 15434.922825385802, 13660.0, 13911.582652520441, 13934.163950163023, 13935.512257070954, 14413.85586144125, 13225.338733063501, 13327.533536326182, 13628.591142265748, 13987.732512837052, 14060.296985150497, 11132.385804058204, 13581.185128892299, 13823.861761382257, 13962.0, 14177.291135443393, 10587.36475811423, 10984.945075274572, 11388.0, 11617.767644647229, 11921.642350729491, 8985.910140898508, 11309.0, 11430.714232144262, 11475.54810254055, 11484.88515114838], "value": 7634.724173575812, "unit": "score"}], "name": "dromaeo_css", "value": 7045.6559835349135, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 110.0, 112.0, 110.0, 111.0, 111.0, 112.0, 111.0, 110.0, 112.0], "value": 110.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 111.0, 113.0, 152.0, 113.0, 115.0, 123.0, 141.0, 178.0, 114.0], "value": 130.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 181.0, 184.0, 183.0, 184.0, 181.0, 185.0, 180.0, 182.0], "value": 182.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 84.0, 61.0, 58.0, 58.0, 58.0, 85.0, 73.0], "value": 67.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 71.0, 90.0, 70.0, 67.0, 69.0, 92.0, 68.0, 68.0, 92.0], "value": 77.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 86.0, 90.0, 85.0, 90.0, 86.0, 91.0, 88.0, 90.0, 87.0], "value": 88.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 96.0, 96.0, 98.0, 125.0, 168.0, 96.0, 97.0, 97.0], "value": 106.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 84.0, 84.0, 83.0, 86.0, 82.0, 85.0, 83.0, 86.0], "value": 84.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 52.0, 54.0, 55.0, 54.0, 53.0, 54.0, 54.0], "value": 53.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 74.0, 79.0, 73.0, 72.0, 67.0, 70.0, 70.0, 69.0, 71.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 87.0, 88.0, 85.0, 88.0, 87.0, 91.0, 87.0, 91.0], "value": 87.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 146.0, 146.0, 145.0, 143.0, 148.0, 140.0, 147.0, 180.0, 154.0], "value": 149.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 177.0, 175.0, 172.0, 233.0, 166.0, 227.0, 239.0, 185.0, 172.0], "value": 191.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 68.0, 68.0, 70.0, 68.0, 69.0, 67.0, 68.0, 67.0, 69.0], "value": 68.4, "unit": "score"}], "name": "kraken", "value": 1470.2000000000003, "alertThreshold": 2.0}]} 03:18:00 INFO - Return code: 0 03:18:00 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 03:18:00 INFO - # TBPL SUCCESS # 03:18:00 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:18:00 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:18:00 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:18:00 INFO - 2016-10-13 03:18:00.826 screenresolution[1665:10384] starting screenresolution argv=screenresolution get 03:18:00 INFO - 2016-10-13 03:18:00.829 screenresolution[1665:10384] Display 0: 1600x1200x32@60 03:18:00 INFO - 2016-10-13 03:18:00.835 screenresolution[1666:10387] starting screenresolution argv=screenresolution list 03:18:00 INFO - Available Modes on Display 0 03:18:00 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:18:00 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:18:00 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:18:00 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:18:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:18:01 INFO - Intel Iris: 03:18:01 INFO - Chipset Model: Intel Iris 03:18:01 INFO - Type: GPU 03:18:01 INFO - Bus: Built-In 03:18:01 INFO - VRAM (Dynamic, Max): 1536 MB 03:18:01 INFO - Vendor: Intel (0x8086) 03:18:01 INFO - Device ID: 0x0a2e 03:18:01 INFO - Revision ID: 0x0009 03:18:01 INFO - Displays: 03:18:01 INFO - Display: 03:18:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:18:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:18:01 INFO - Display Serial Number: 1600x1200 60 03:18:01 INFO - Main Display: Yes 03:18:01 INFO - Mirror: Off 03:18:01 INFO - Online: Yes 03:18:01 INFO - Rotation: Supported 03:18:01 INFO - Return code: 0 03:18:01 INFO - Running post-action listener: _resource_record_post_action 03:18:01 INFO - [mozharness: 2016-10-13 10:18:01.075428Z] Finished run-tests step (success) 03:18:01 INFO - Running post-run listener: _resource_record_post_run 03:18:01 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 33016832; Write bytes: 711500288; Read time: 308; Write time: 1949 03:18:01 INFO - TinderboxPrint: CPU usage
53.3% 03:18:01 INFO - TinderboxPrint: I/O read bytes / time
33,016,832 / 308 03:18:01 INFO - TinderboxPrint: I/O write bytes / time
711,500,288 / 1,949 03:18:01 INFO - TinderboxPrint: CPU idle
2,270.2 (46.7%) 03:18:01 INFO - TinderboxPrint: CPU system
971.0 (20.0%) 03:18:01 INFO - TinderboxPrint: CPU user
1,620.6 (33.3%) 03:18:01 INFO - TinderboxPrint: Swap in / out
436,318,208 / 0 03:18:01 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387021312; Write bytes: 379906048; Read time: 28965; Write time: 735 03:18:01 INFO - run-tests - Wall time: 1181s; CPU: 53.0%; Read bytes: 32091136; Write bytes: 300886528; Read time: 293; Write time: 1154 03:18:01 INFO - Running post-run listener: _upload_blobber_files 03:18:01 INFO - Blob upload gear active. 03:18:01 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 03:18:01 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 03:18:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 03:18:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 03:18:01 INFO - (blobuploader) - INFO - Open directory for files ... 03:18:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 03:18:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:02 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 03:18:02 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:02 INFO - (blobuploader) - INFO - Done attempting. 03:18:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 03:18:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 03:18:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:03 INFO - (blobuploader) - INFO - Done attempting. 03:18:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 03:18:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 03:18:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 03:18:05 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 03:18:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 03:18:05 INFO - (blobuploader) - INFO - Done attempting. 03:18:05 INFO - (blobuploader) - INFO - Iteration through files over. 03:18:05 INFO - Return code: 0 03:18:05 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 03:18:05 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 03:18:05 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c44c300bcf677b9726dec6b0143f37f156077f70a391b99ce09f3ecbcc3c8dc9a7402200f4823c0e133840f8911a69d9514a665b8ac540aa9530b785433b9ac0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6e7f866ac0f946298948f0d14b094c17c4501f8d44cf06a517192b41519edc2fc11c98fe1b2fed8d809aa34780c75f92d35862393fda171a1c71ad10be5ff624", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f114a4b770dcd936eadf65254ea21d7b2df456a2f4d5526368a3cde5ef4ff65a8bcd5c0336d2aa552946e1e4476a36e978cc993fe9d80131585cc48dd87b583f"} 03:18:05 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 03:18:05 INFO - Writing to file /builds/slave/test/properties/blobber_files 03:18:05 INFO - Contents: 03:18:05 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c44c300bcf677b9726dec6b0143f37f156077f70a391b99ce09f3ecbcc3c8dc9a7402200f4823c0e133840f8911a69d9514a665b8ac540aa9530b785433b9ac0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6e7f866ac0f946298948f0d14b094c17c4501f8d44cf06a517192b41519edc2fc11c98fe1b2fed8d809aa34780c75f92d35862393fda171a1c71ad10be5ff624", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f114a4b770dcd936eadf65254ea21d7b2df456a2f4d5526368a3cde5ef4ff65a8bcd5c0336d2aa552946e1e4476a36e978cc993fe9d80131585cc48dd87b583f"} 03:18:05 INFO - Running post-run listener: copy_logs_to_upload_dir 03:18:05 INFO - Copying logs to upload dir... 03:18:05 INFO - mkdir: /builds/slave/test/build/upload/logs 03:18:05 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.474226 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-10-13 03:18:05.976062) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 03:18:05.982292) ========= 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.iQb96mJcDU/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.0g9cq1dyNU/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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/Autoland/sha512/c44c300bcf677b9726dec6b0143f37f156077f70a391b99ce09f3ecbcc3c8dc9a7402200f4823c0e133840f8911a69d9514a665b8ac540aa9530b785433b9ac0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6e7f866ac0f946298948f0d14b094c17c4501f8d44cf06a517192b41519edc2fc11c98fe1b2fed8d809aa34780c75f92d35862393fda171a1c71ad10be5ff624", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f114a4b770dcd936eadf65254ea21d7b2df456a2f4d5526368a3cde5ef4ff65a8bcd5c0336d2aa552946e1e4476a36e978cc993fe9d80131585cc48dd87b583f"} build_url:https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010971 build_url: 'https://queue.taskcluster.net/v1/task/BVAUeoQHSzKnMf_inkEQFw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/c44c300bcf677b9726dec6b0143f37f156077f70a391b99ce09f3ecbcc3c8dc9a7402200f4823c0e133840f8911a69d9514a665b8ac540aa9530b785433b9ac0", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/6e7f866ac0f946298948f0d14b094c17c4501f8d44cf06a517192b41519edc2fc11c98fe1b2fed8d809aa34780c75f92d35862393fda171a1c71ad10be5ff624", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f114a4b770dcd936eadf65254ea21d7b2df456a2f4d5526368a3cde5ef4ff65a8bcd5c0336d2aa552946e1e4476a36e978cc993fe9d80131585cc48dd87b583f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 03:18:06.014815) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:18:06.015157) ========= 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.iQb96mJcDU/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.0g9cq1dyNU/Listeners TMPDIR=/var/folders/cw/zb33kjnn0fl7x243_8cyf2fm00000w/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.005198 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 03:18:06.063742) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 03:18:06.064060) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 03:18:06.064398) ========= ========= Total master_lag: 0.18 =========