builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0307 starttime: 1477178220.85 results: success (0) buildid: 20161022135722 builduid: 62b68978fa2c4981bb851c6a2c44a8d0 revision: 60dd82380d43a2b681f50842238f829204486290 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:00.852207) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:00.852647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:00.852924) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:00.883621) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:00.883878) ========= 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.NMBPRSBhxD/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.9FPM6E79ON/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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-22 16:17:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-10-22 16:17:01 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.479115 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:01.406364) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:01.406740) ========= 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.NMBPRSBhxD/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.9FPM6E79ON/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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.024525 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:01.467766) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-22 16:17:01.468212) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 60dd82380d43a2b681f50842238f829204486290 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 60dd82380d43a2b681f50842238f829204486290 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NMBPRSBhxD/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.9FPM6E79ON/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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-22 16:17:01,559 truncating revision to first 12 chars 2016-10-22 16:17:01,559 Setting DEBUG logging. 2016-10-22 16:17:01,559 attempt 1/10 2016-10-22 16:17:01,559 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/60dd82380d43?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-22 16:17:02,578 unpacking tar archive at: mozilla-central-60dd82380d43/testing/mozharness/ program finished with exit code 0 elapsedTime=1.278754 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-22 16:17:02.777451) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:02.777758) ========= script_repo_revision: 60dd82380d43a2b681f50842238f829204486290 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:02.781280) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:02.781598) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-22 16:17:02.799849) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-22 16:17:02.800257) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.NMBPRSBhxD/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.9FPM6E79ON/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:02 INFO - MultiFileLogger online at 20161022 16:17:02 in /builds/slave/test 16:17:02 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 16:17:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 16:17:02 INFO - {'append_to_log': False, 16:17:02 INFO - 'base_work_dir': '/builds/slave/test', 16:17:02 INFO - 'blob_upload_branch': 'Firefox', 16:17:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 16:17:02 INFO - 'branch': 'Firefox', 16:17:02 INFO - 'buildbot_json_path': 'buildprops.json', 16:17:02 INFO - 'config_files': ('talos/mac_config.py',), 16:17:02 INFO - 'default_actions': ('clobber', 16:17:02 INFO - 'read-buildbot-config', 16:17:03 INFO - 'download-and-extract', 16:17:03 INFO - 'populate-webroot', 16:17:03 INFO - 'create-virtualenv', 16:17:03 INFO - 'install', 16:17:03 INFO - 'run-tests'), 16:17:03 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 16:17:03 INFO - 'download_minidump_stackwalk': True, 16:17:03 INFO - 'download_symbols': 'ondemand', 16:17:03 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 16:17:03 INFO - 'tooltool.py': '/tools/tooltool.py', 16:17:03 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 16:17:03 INFO - '/tools/misc-python/virtualenv.py')}, 16:17:03 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 16:17:03 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 16:17:03 INFO - 'installer_path': 'installer.exe', 16:17:03 INFO - 'log_level': 'info', 16:17:03 INFO - 'log_name': 'talos', 16:17:03 INFO - 'log_to_console': True, 16:17:03 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 16:17:03 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 16:17:03 INFO - 'opt_config_files': (), 16:17:03 INFO - 'pip_index': False, 16:17:03 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:17:03 INFO - 'cmd': ('bash', 16:17:03 INFO - '-c', 16:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:17:03 INFO - 'enabled': True, 16:17:03 INFO - 'halt_on_failure': False, 16:17:03 INFO - 'name': 'check_screen_resolution'},), 16:17:03 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 16:17:03 INFO - 'cmd': ('bash', 16:17:03 INFO - '-c', 16:17:03 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 16:17:03 INFO - 'enabled': True, 16:17:03 INFO - 'halt_on_failure': False, 16:17:03 INFO - 'name': 'check_screen_resolution'},), 16:17:03 INFO - 'run_cmd_checks_enabled': True, 16:17:03 INFO - 'sps_profile': False, 16:17:03 INFO - 'sps_profile_interval': 0, 16:17:03 INFO - 'suite': 'dromaeojs-e10s', 16:17:03 INFO - 'system_bits': '32', 16:17:03 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 16:17:03 INFO - 'title': 't-yosemite-r7-0307', 16:17:03 INFO - 'tooltool_cache': '/builds/tooltool_cache', 16:17:03 INFO - 'use_talos_json': True, 16:17:03 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 16:17:03 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 16:17:03 INFO - 'work_dir': 'build'} 16:17:03 INFO - [mozharness: 2016-10-22 23:17:03.005020Z] Running clobber step. 16:17:03 INFO - Running pre-action listener: _resource_record_pre_action 16:17:03 INFO - Running main action method: clobber 16:17:03 INFO - rmtree: /builds/slave/test/build 16:17:03 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 16:17:05 INFO - Running post-action listener: _resource_record_post_action 16:17:05 INFO - [mozharness: 2016-10-22 23:17:05.118956Z] Finished clobber step (success) 16:17:05 INFO - [mozharness: 2016-10-22 23:17:05.119154Z] Running read-buildbot-config step. 16:17:05 INFO - Running pre-action listener: _resource_record_pre_action 16:17:05 INFO - Running main action method: read_buildbot_config 16:17:05 INFO - Using buildbot properties: 16:17:05 INFO - { 16:17:05 INFO - "project": "", 16:17:05 INFO - "product": "firefox", 16:17:05 INFO - "script_repo_revision": "production", 16:17:05 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 16:17:05 INFO - "repository": "", 16:17:05 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 16:17:05 INFO - "buildid": "20161022135722", 16:17:05 INFO - "pgo_build": "False", 16:17:05 INFO - "basedir": "/builds/slave/test", 16:17:05 INFO - "buildnumber": 148, 16:17:05 INFO - "slavename": "t-yosemite-r7-0307", 16:17:05 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 16:17:05 INFO - "platform": "yosemite_r7", 16:17:05 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 16:17:05 INFO - "branch": "mozilla-central", 16:17:05 INFO - "repo_path": "mozilla-central", 16:17:05 INFO - "revision": "60dd82380d43a2b681f50842238f829204486290", 16:17:05 INFO - "stage_platform": "macosx64", 16:17:05 INFO - "builduid": "62b68978fa2c4981bb851c6a2c44a8d0", 16:17:05 INFO - "slavebuilddir": "test" 16:17:05 INFO - } 16:17:05 INFO - Found installer url https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 16:17:05 INFO - Running post-action listener: _resource_record_post_action 16:17:05 INFO - [mozharness: 2016-10-22 23:17:05.124680Z] Finished read-buildbot-config step (success) 16:17:05 INFO - [mozharness: 2016-10-22 23:17:05.124899Z] Running download-and-extract step. 16:17:05 INFO - Running pre-action listener: _resource_record_pre_action 16:17:05 INFO - Running main action method: download_and_extract 16:17:05 INFO - mkdir: /builds/slave/test/build/tests 16:17:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:05 INFO - trying https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 16:17:05 INFO - Downloading https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/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 16:17:05 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/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 16:17:06 INFO - Downloaded 1199 bytes. 16:17:06 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 16:17:06 INFO - Using the following test package requirements: 16:17:06 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:17:06 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 16:17:06 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 16:17:06 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'jsshell-mac.zip'], 16:17:06 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 16:17:06 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 16:17:06 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 16:17:06 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 16:17:06 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 16:17:06 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 16:17:06 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 16:17:06 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 16:17:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:17:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:06 INFO - Fetch https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:17:07 INFO - Http code: 200 16:17:07 INFO - via: 1.1 5c2ff4ca1e447265402af29264e83497.cloudfront.net (CloudFront) 16:17:07 INFO - x-amz-cf-id: 3ejkd02gEY305ZqFHQnyxW7QGGTxs9LtPKF22zMn02MgorKR_TNOEA== 16:17:07 INFO - x-amz-version-id: qrFZ619wlXN.z0zpJ21GiFRRMHRbAPUZ 16:17:07 INFO - Expected file size: 16420834 16:17:07 INFO - Obtained file size: 16420834 16:17:14 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 16:17:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 16:17:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 16:17:14 INFO - Fetch https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 16:17:14 INFO - Http code: 200 16:17:14 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 16:17:14 INFO - x-amz-cf-id: R3gzOz2VbOLVMH5q3bhOtHfWaEXsaO5Ichdj3iYVu1m1vdZjcdUvgw== 16:17:14 INFO - x-amz-version-id: qrFZ619wlXN.z0zpJ21GiFRRMHRbAPUZ 16:17:15 INFO - Expected file size: 16420834 16:17:15 INFO - Obtained file size: 16420834 16:17:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 16:17:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 16:17:21 INFO - Fetch https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 16:17:22 INFO - Http code: 200 16:17:22 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 16:17:22 INFO - x-amz-cf-id: V5qkQL_wUo6D-Y32Db3G6M6MXm2hwTw80tHX09N0AI0oeR2qqYndSQ== 16:17:22 INFO - x-amz-version-id: 5D2swXQBnIaHteb_iqaz0roWr284sOZ0 16:17:22 INFO - Expected file size: 14409028 16:17:22 INFO - Obtained file size: 14409028 16:17:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:23 INFO - trying https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:17:23 INFO - Downloading https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 16:17:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/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 16:17:25 INFO - Downloaded 111334592 bytes. 16:17:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:17:25 INFO - mkdir: /builds/slave/test/properties 16:17:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 16:17:25 INFO - Writing to file /builds/slave/test/properties/build_url 16:17:25 INFO - Contents: 16:17:25 INFO - build_url:https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 16:17:27 INFO - Running post-action listener: _resource_record_post_action 16:17:27 INFO - Running post-action listener: set_extra_try_arguments 16:17:27 INFO - [mozharness: 2016-10-22 23:17:27.186915Z] Finished download-and-extract step (success) 16:17:27 INFO - [mozharness: 2016-10-22 23:17:27.187042Z] Running populate-webroot step. 16:17:27 INFO - Running pre-action listener: _resource_record_pre_action 16:17:27 INFO - Running main action method: populate_webroot 16:17:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 16:17:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 16:17:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 16:17:27 INFO - '--tppagecycles', 16:17:27 INFO - '7'], 16:17:27 INFO - 'tests': ['tsvgm']}}, 16:17:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 16:17:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 16:17:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 16:17:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 16:17:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 16:17:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['damp', 'tps']}, 16:17:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'tests': ['damp', 'tps']}, 16:17:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['dromaeo_dom']}, 16:17:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 16:17:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['basic_compositor_video']}, 16:17:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 16:17:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['a11yr', 16:17:27 INFO - 'ts_paint', 16:17:27 INFO - 'tpaint', 16:17:27 INFO - 'sessionrestore', 16:17:27 INFO - 'sessionrestore_no_auto_restore', 16:17:27 INFO - 'tabpaint']}, 16:17:27 INFO - 'other-e10s': {'tests': ['a11yr', 16:17:27 INFO - 'ts_paint', 16:17:27 INFO - 'tpaint', 16:17:27 INFO - 'sessionrestore', 16:17:27 INFO - 'sessionrestore_no_auto_restore', 16:17:27 INFO - 'tabpaint']}, 16:17:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 16:17:27 INFO - 'ts_paint', 16:17:27 INFO - 'tpaint', 16:17:27 INFO - 'sessionrestore', 16:17:27 INFO - 'sessionrestore_no_auto_restore', 16:17:27 INFO - 'tabpaint']}, 16:17:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 16:17:27 INFO - 'ts_paint', 16:17:27 INFO - 'tpaint', 16:17:27 INFO - 'sessionrestore', 16:17:27 INFO - 'sessionrestore_no_auto_restore', 16:17:27 INFO - 'tabpaint']}, 16:17:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['a11yr', 16:17:27 INFO - 'ts_paint', 16:17:27 INFO - 'tpaint', 16:17:27 INFO - 'sessionrestore', 16:17:27 INFO - 'sessionrestore_no_auto_restore', 16:17:27 INFO - 'tabpaint']}, 16:17:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['a11yr', 16:17:27 INFO - 'ts_paint', 16:17:27 INFO - 'tpaint', 16:17:27 INFO - 'sessionrestore', 16:17:27 INFO - 'sessionrestore_no_auto_restore', 16:17:27 INFO - 'tabpaint']}, 16:17:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['tsvgx', 16:17:27 INFO - 'tsvgr_opacity', 16:17:27 INFO - 'tart', 16:17:27 INFO - 'tscrollx', 16:17:27 INFO - 'cart']}, 16:17:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 16:17:27 INFO - 'tsvgr_opacity', 16:17:27 INFO - 'tart', 16:17:27 INFO - 'tscrollx', 16:17:27 INFO - 'cart']}, 16:17:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'talos_options': ['--disable-e10s'], 16:17:27 INFO - 'tests': ['tp5o']}, 16:17:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'tests': ['tp5o']}, 16:17:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'talos_options': ['--disable-e10s', 16:17:27 INFO - '--xperf_path', 16:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:27 INFO - 'tests': ['tp5n']}, 16:17:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 16:17:27 INFO - 'talos_options': ['--xperf_path', 16:17:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 16:17:27 INFO - 'tests': ['tp5n']}}, 16:17:27 INFO - 'talos.zip': {'path': '', 16:17:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 16:17:27 INFO - Running post-action listener: _resource_record_post_action 16:17:27 INFO - [mozharness: 2016-10-22 23:17:27.205774Z] Finished populate-webroot step (success) 16:17:27 INFO - [mozharness: 2016-10-22 23:17:27.205904Z] Running create-virtualenv step. 16:17:27 INFO - Running pre-action listener: _resource_record_pre_action 16:17:27 INFO - Running main action method: create_virtualenv 16:17:27 INFO - Creating virtualenv /builds/slave/test/build/venv 16:17:27 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 16:17:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 16:17:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 16:17:27 INFO - Using real prefix '/tools/python27' 16:17:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 16:17:28 INFO - Installing distribute.............................................................................................................................................................................................done. 16:17:31 INFO - Installing pip.................done. 16:17:31 INFO - Return code: 0 16:17:31 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 16:17:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 16:17:31 INFO - Reading from file tmpfile_stdout 16:17:31 INFO - Output received: 16:17:31 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) 16:17:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 16:17:31 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')]} 16:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:31 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')]} 16:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:31 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:31 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 16:17:31 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 16:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:31 INFO - 'HOME': '/Users/cltbld', 16:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:31 INFO - 'LOGNAME': 'cltbld', 16:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:31 INFO - 'MOZ_NO_REMOTE': '1', 16:17:31 INFO - 'NO_EM_RESTART': '1', 16:17:31 INFO - 'PAGER': '/bin/cat', 16:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:31 INFO - 'PWD': '/builds/slave/test', 16:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:31 INFO - 'SHELL': '/bin/bash', 16:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:31 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:31 INFO - 'USER': 'cltbld', 16:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:31 INFO - 'XPC_FLAGS': '0x0', 16:17:31 INFO - 'XPC_SERVICE_NAME': '0', 16:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:31 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 16:17:31 INFO - Cleaning up... 16:17:31 INFO - Return code: 0 16:17:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 16:17:31 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')]} 16:17:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:31 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')]} 16:17:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:31 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:31 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 16:17:31 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 16:17:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:31 INFO - 'HOME': '/Users/cltbld', 16:17:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:31 INFO - 'LOGNAME': 'cltbld', 16:17:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:31 INFO - 'MOZ_NO_REMOTE': '1', 16:17:31 INFO - 'NO_EM_RESTART': '1', 16:17:31 INFO - 'PAGER': '/bin/cat', 16:17:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:31 INFO - 'PWD': '/builds/slave/test', 16:17:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:31 INFO - 'SHELL': '/bin/bash', 16:17:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:31 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:31 INFO - 'USER': 'cltbld', 16:17:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:31 INFO - 'XPC_FLAGS': '0x0', 16:17:31 INFO - 'XPC_SERVICE_NAME': '0', 16:17:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:31 INFO - Downloading/unpacking psutil>=3.1.1 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:31 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 16:17:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 16:17:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:35 INFO - Installing collected packages: psutil 16:17:35 INFO - Running setup.py install for psutil 16:17:35 INFO - building 'psutil._psutil_osx' extension 16:17:35 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 16:17:36 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 16:17:36 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 16:17:36 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 16:17:36 INFO - building 'psutil._psutil_posix' extension 16:17:36 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 16:17:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 16:17:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 16:17:36 INFO - ^ 16:17:36 INFO - 1 warning generated. 16:17:36 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 16:17:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 16:17:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 16:17:36 INFO - Successfully installed psutil 16:17:36 INFO - Cleaning up... 16:17:36 INFO - Return code: 0 16:17:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 16:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 16:17:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 16:17:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:36 INFO - 'HOME': '/Users/cltbld', 16:17:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:36 INFO - 'LOGNAME': 'cltbld', 16:17:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:36 INFO - 'MOZ_NO_REMOTE': '1', 16:17:36 INFO - 'NO_EM_RESTART': '1', 16:17:36 INFO - 'PAGER': '/bin/cat', 16:17:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:36 INFO - 'PWD': '/builds/slave/test', 16:17:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:36 INFO - 'SHELL': '/bin/bash', 16:17:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:36 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:36 INFO - 'USER': 'cltbld', 16:17:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:36 INFO - 'XPC_FLAGS': '0x0', 16:17:36 INFO - 'XPC_SERVICE_NAME': '0', 16:17:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 16:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 16:17:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 16:17:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 16:17:39 INFO - Installing collected packages: mozsystemmonitor 16:17:39 INFO - Running setup.py install for mozsystemmonitor 16:17:39 INFO - Successfully installed mozsystemmonitor 16:17:39 INFO - Cleaning up... 16:17:40 INFO - Return code: 0 16:17:40 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 16:17:40 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')]} 16:17:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:40 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')]} 16:17:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:40 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:40 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 16:17:40 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 16:17:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:40 INFO - 'HOME': '/Users/cltbld', 16:17:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:40 INFO - 'LOGNAME': 'cltbld', 16:17:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:40 INFO - 'MOZ_NO_REMOTE': '1', 16:17:40 INFO - 'NO_EM_RESTART': '1', 16:17:40 INFO - 'PAGER': '/bin/cat', 16:17:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:40 INFO - 'PWD': '/builds/slave/test', 16:17:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:40 INFO - 'SHELL': '/bin/bash', 16:17:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:40 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:40 INFO - 'USER': 'cltbld', 16:17:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:40 INFO - 'XPC_FLAGS': '0x0', 16:17:40 INFO - 'XPC_SERVICE_NAME': '0', 16:17:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:40 INFO - Downloading/unpacking jsonschema==2.5.1 16:17:40 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 16:17:40 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 16:17:40 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 16:17:40 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 16:17:40 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 16:17:40 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 16:17:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 16:17:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 16:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:43 INFO - Downloading functools32-3.2.3-2.tar.gz 16:17:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 16:17:43 INFO - warning: no files found matching '*.txt' 16:17:43 INFO - no previously-included directories found matching 'build' 16:17:43 INFO - no previously-included directories found matching 'dist' 16:17:43 INFO - no previously-included directories found matching '.git*' 16:17:43 INFO - Installing collected packages: jsonschema, functools32 16:17:43 INFO - Running setup.py install for functools32 16:17:43 INFO - warning: no files found matching '*.txt' 16:17:43 INFO - no previously-included directories found matching 'build' 16:17:43 INFO - no previously-included directories found matching 'dist' 16:17:43 INFO - no previously-included directories found matching '.git*' 16:17:43 INFO - Successfully installed jsonschema functools32 16:17:43 INFO - Cleaning up... 16:17:43 INFO - Return code: 0 16:17:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 16:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:43 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:43 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 16:17:43 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 16:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:43 INFO - 'HOME': '/Users/cltbld', 16:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:43 INFO - 'LOGNAME': 'cltbld', 16:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:43 INFO - 'MOZ_NO_REMOTE': '1', 16:17:43 INFO - 'NO_EM_RESTART': '1', 16:17:43 INFO - 'PAGER': '/bin/cat', 16:17:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:43 INFO - 'PWD': '/builds/slave/test', 16:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:43 INFO - 'SHELL': '/bin/bash', 16:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:43 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:43 INFO - 'USER': 'cltbld', 16:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:43 INFO - 'XPC_FLAGS': '0x0', 16:17:43 INFO - 'XPC_SERVICE_NAME': '0', 16:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 16:17:43 INFO - Cleaning up... 16:17:43 INFO - Return code: 0 16:17:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 16:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:43 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:43 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 16:17:43 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 16:17:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:43 INFO - 'HOME': '/Users/cltbld', 16:17:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:43 INFO - 'LOGNAME': 'cltbld', 16:17:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:43 INFO - 'MOZ_NO_REMOTE': '1', 16:17:43 INFO - 'NO_EM_RESTART': '1', 16:17:43 INFO - 'PAGER': '/bin/cat', 16:17:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:43 INFO - 'PWD': '/builds/slave/test', 16:17:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:43 INFO - 'SHELL': '/bin/bash', 16:17:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:43 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:43 INFO - 'USER': 'cltbld', 16:17:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:43 INFO - 'XPC_FLAGS': '0x0', 16:17:43 INFO - 'XPC_SERVICE_NAME': '0', 16:17:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:44 INFO - Downloading/unpacking blobuploader==1.2.4 16:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:46 INFO - Downloading blobuploader-1.2.4.tar.gz 16:17:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 16:17:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 16:17:46 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 16:17:46 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 16:17:46 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 16:17:46 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 16:17:46 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 16:17:46 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 16:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 16:17:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 16:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 16:17:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 16:17:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 16:17:47 INFO - Downloading docopt-0.6.1.tar.gz 16:17:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 16:17:47 INFO - Installing collected packages: blobuploader, requests, docopt 16:17:47 INFO - Running setup.py install for blobuploader 16:17:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 16:17:48 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 16:17:48 INFO - Running setup.py install for requests 16:17:48 INFO - Running setup.py install for docopt 16:17:48 INFO - Successfully installed blobuploader requests docopt 16:17:48 INFO - Cleaning up... 16:17:48 INFO - Return code: 0 16:17:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:48 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:48 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 16:17:48 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 16:17:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:48 INFO - 'HOME': '/Users/cltbld', 16:17:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:48 INFO - 'LOGNAME': 'cltbld', 16:17:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:48 INFO - 'MOZ_NO_REMOTE': '1', 16:17:48 INFO - 'NO_EM_RESTART': '1', 16:17:48 INFO - 'PAGER': '/bin/cat', 16:17:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:48 INFO - 'PWD': '/builds/slave/test', 16:17:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:48 INFO - 'SHELL': '/bin/bash', 16:17:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:48 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:48 INFO - 'USER': 'cltbld', 16:17:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:48 INFO - 'XPC_FLAGS': '0x0', 16:17:48 INFO - 'XPC_SERVICE_NAME': '0', 16:17:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-FHhdOR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-edPByv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-rgY86c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-19BNOG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-0B3xvk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-2cDI5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-khk01O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-QzTaWz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-Pc4_9t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:49 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-IkwjtK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-v9utyb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-QHjP_U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-kjhsAm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-CXRrj_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-XcupVa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-My0bIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:50 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-rSRCl1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 16:17:50 INFO - Running setup.py install for manifestparser 16:17:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 16:17:50 INFO - Running setup.py install for mozcrash 16:17:51 INFO - Running setup.py install for mozdebug 16:17:51 INFO - Running setup.py install for mozdevice 16:17:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 16:17:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 16:17:51 INFO - Running setup.py install for mozfile 16:17:51 INFO - Running setup.py install for mozhttpd 16:17:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 16:17:51 INFO - Running setup.py install for mozinfo 16:17:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 16:17:51 INFO - Running setup.py install for mozInstall 16:17:52 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Running setup.py install for mozleak 16:17:52 INFO - Running setup.py install for mozlog 16:17:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Running setup.py install for moznetwork 16:17:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Running setup.py install for mozprocess 16:17:52 INFO - Running setup.py install for mozprofile 16:17:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Running setup.py install for mozrunner 16:17:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 16:17:52 INFO - Running setup.py install for mozscreenshot 16:17:53 INFO - Running setup.py install for moztest 16:17:53 INFO - Running setup.py install for mozversion 16:17:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 16:17:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 16:17:53 INFO - Cleaning up... 16:17:53 INFO - Return code: 0 16:17:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 16:17:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 16:17:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 16:17:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:53 INFO - 'HOME': '/Users/cltbld', 16:17:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:53 INFO - 'LOGNAME': 'cltbld', 16:17:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:53 INFO - 'MOZ_NO_REMOTE': '1', 16:17:53 INFO - 'NO_EM_RESTART': '1', 16:17:53 INFO - 'PAGER': '/bin/cat', 16:17:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:53 INFO - 'PWD': '/builds/slave/test', 16:17:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:53 INFO - 'SHELL': '/bin/bash', 16:17:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:53 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:53 INFO - 'USER': 'cltbld', 16:17:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:53 INFO - 'XPC_FLAGS': '0x0', 16:17:53 INFO - 'XPC_SERVICE_NAME': '0', 16:17:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 16:17:53 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-niVZAr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 16:17:53 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)) 16:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 16:17:53 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-3FEou8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 16:17:53 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)) 16:17:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 16:17:53 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-78TfQ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 16:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-ZCg12j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 16:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-PMLcQg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 16:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-ezqc30-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 16:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-9JUdjB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 16:17:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-wf8ipK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 16:17:54 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)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-XVLbI7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 16:17:54 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)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-vDKEPy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 16:17:54 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)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-j95Zje-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 16:17:54 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)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-dPhSvL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 16:17:54 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)) 16:17:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 16:17:54 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-BGxv2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 16:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 16:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 16:17:55 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-Rff34M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 16:17:55 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)) 16:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:55 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-3hMRGk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 16:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 16:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 16:17:55 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-YdoELH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 16:17:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 16:17:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 16:17:55 INFO - Running setup.py (path:/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/pip-ZTBm4d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 16:17:55 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)) 16:17:55 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)) 16:17:55 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)) 16:17:55 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)) 16:17:55 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)) 16:17:55 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)) 16:17:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:55 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 16:17:58 INFO - Downloading blessings-1.6.tar.gz 16:17:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 16:17:58 INFO - Installing collected packages: blessings 16:17:58 INFO - Running setup.py install for blessings 16:17:58 INFO - Successfully installed blessings 16:17:58 INFO - Cleaning up... 16:17:58 INFO - Return code: 0 16:17:58 INFO - Done creating virtualenv /builds/slave/test/build/venv. 16:17:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:58 INFO - Reading from file tmpfile_stdout 16:17:58 INFO - Current package versions: 16:17:58 INFO - blessings == 1.6 16:17:58 INFO - blobuploader == 1.2.4 16:17:58 INFO - docopt == 0.6.1 16:17:58 INFO - functools32 == 3.2.3-2 16:17:58 INFO - jsonschema == 2.5.1 16:17:58 INFO - manifestparser == 1.1 16:17:58 INFO - mozInstall == 1.12 16:17:58 INFO - mozcrash == 0.17 16:17:58 INFO - mozdebug == 0.1 16:17:58 INFO - mozdevice == 0.48 16:17:58 INFO - mozfile == 1.2 16:17:58 INFO - mozhttpd == 0.7 16:17:58 INFO - mozinfo == 0.9 16:17:58 INFO - mozleak == 0.1 16:17:58 INFO - mozlog == 3.4 16:17:58 INFO - moznetwork == 0.27 16:17:58 INFO - mozprocess == 0.23 16:17:58 INFO - mozprofile == 0.28 16:17:58 INFO - mozrunner == 6.12 16:17:58 INFO - mozscreenshot == 0.1 16:17:58 INFO - mozsystemmonitor == 0.3 16:17:58 INFO - moztest == 0.7 16:17:58 INFO - mozversion == 1.4 16:17:58 INFO - psutil == 3.1.1 16:17:58 INFO - requests == 1.2.3 16:17:58 INFO - wsgiref == 0.1.2 16:17:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 16:17:58 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')]} 16:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:58 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')]} 16:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:58 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:58 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 16:17:58 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 16:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:58 INFO - 'HOME': '/Users/cltbld', 16:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:58 INFO - 'LOGNAME': 'cltbld', 16:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:58 INFO - 'MOZ_NO_REMOTE': '1', 16:17:58 INFO - 'NO_EM_RESTART': '1', 16:17:58 INFO - 'PAGER': '/bin/cat', 16:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:58 INFO - 'PWD': '/builds/slave/test', 16:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:58 INFO - 'SHELL': '/bin/bash', 16:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:58 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:58 INFO - 'USER': 'cltbld', 16:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:58 INFO - 'XPC_FLAGS': '0x0', 16:17:58 INFO - 'XPC_SERVICE_NAME': '0', 16:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 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)) 16:17:58 INFO - Cleaning up... 16:17:58 INFO - Return code: 0 16:17:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 16:17:58 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')]} 16:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 16:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:58 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')]} 16:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 16:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 16:17:58 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 0x101114490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10108a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffe234d90e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, '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.NMBPRSBhxD/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.9FPM6E79ON/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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 16:17:58 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 16:17:58 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 16:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:17:58 INFO - 'HOME': '/Users/cltbld', 16:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:17:58 INFO - 'LOGNAME': 'cltbld', 16:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:17:58 INFO - 'MOZ_NO_REMOTE': '1', 16:17:58 INFO - 'NO_EM_RESTART': '1', 16:17:58 INFO - 'PAGER': '/bin/cat', 16:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:17:58 INFO - 'PWD': '/builds/slave/test', 16:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:17:58 INFO - 'SHELL': '/bin/bash', 16:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:17:58 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:17:58 INFO - 'USER': 'cltbld', 16:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:17:58 INFO - 'XPC_FLAGS': '0x0', 16:17:58 INFO - 'XPC_SERVICE_NAME': '0', 16:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:17:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 16:17:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 16:17:59 INFO - Cleaning up... 16:17:59 INFO - Return code: 0 16:17:59 INFO - Running post-action listener: _resource_record_post_action 16:17:59 INFO - Running post-action listener: _start_resource_monitoring 16:17:59 INFO - Starting resource monitoring. 16:17:59 INFO - [mozharness: 2016-10-22 23:17:59.262553Z] Finished create-virtualenv step (success) 16:17:59 INFO - [mozharness: 2016-10-22 23:17:59.263519Z] Running install step. 16:17:59 INFO - Running pre-action listener: _resource_record_pre_action 16:17:59 INFO - Running main action method: install 16:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 16:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 16:17:59 INFO - Reading from file tmpfile_stdout 16:17:59 INFO - Detecting whether we're running mozinstall >=1.0... 16:17:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 16:17:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 16:17:59 INFO - Reading from file tmpfile_stdout 16:17:59 INFO - Output received: 16:17:59 INFO - Usage: mozinstall [options] installer 16:17:59 INFO - Options: 16:17:59 INFO - -h, --help show this help message and exit 16:17:59 INFO - -d DEST, --destination=DEST 16:17:59 INFO - Directory to install application into. [default: 16:17:59 INFO - "/builds/slave/test"] 16:17:59 INFO - --app=APP Application being installed. [default: firefox] 16:17:59 INFO - mkdir: /builds/slave/test/build/application 16:17:59 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'] 16:17:59 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 16:18:34 INFO - Reading from file tmpfile_stdout 16:18:34 INFO - Output received: 16:18:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 16:18:34 INFO - Running post-action listener: _resource_record_post_action 16:18:34 INFO - [mozharness: 2016-10-22 23:18:34.577116Z] Finished install step (success) 16:18:34 INFO - [mozharness: 2016-10-22 23:18:34.577274Z] Running run-tests step. 16:18:34 INFO - Running pre-action listener: _resource_record_pre_action 16:18:34 INFO - Running main action method: run_tests 16:18:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:18:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:18:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:18:34 INFO - 2016-10-22 16:18:34.591 screenresolution[1646:8109] starting screenresolution argv=screenresolution get 16:18:34 INFO - 2016-10-22 16:18:34.594 screenresolution[1646:8109] Display 0: 1600x1200x32@60 16:18:34 INFO - 2016-10-22 16:18:34.600 screenresolution[1647:8111] starting screenresolution argv=screenresolution list 16:18:34 INFO - Available Modes on Display 0 16:18:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:18:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:18:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:18:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:18:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:18:34 INFO - Intel Iris: 16:18:34 INFO - Chipset Model: Intel Iris 16:18:34 INFO - Type: GPU 16:18:34 INFO - Bus: Built-In 16:18:34 INFO - VRAM (Dynamic, Max): 1536 MB 16:18:34 INFO - Vendor: Intel (0x8086) 16:18:34 INFO - Device ID: 0x0a2e 16:18:34 INFO - Revision ID: 0x0009 16:18:34 INFO - Displays: 16:18:34 INFO - Display: 16:18:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:18:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:18:34 INFO - Display Serial Number: 1600x1200 60 16:18:34 INFO - Main Display: Yes 16:18:34 INFO - Mirror: Off 16:18:34 INFO - Online: Yes 16:18:34 INFO - Rotation: Supported 16:18:34 INFO - Return code: 0 16:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 16:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 16:18:34 INFO - Python 2.7.3 16:18:34 INFO - Return code: 0 16:18:34 INFO - grabbing minidump binary from tooltool 16:18: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')]} 16:18:34 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 0x100f28d30>, '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 0x100d4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f20b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 16:18:34 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 16:18:34 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 16:18:34 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 16:18:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 16:18:34 INFO - Return code: 0 16:18:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 16:18:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 16:18:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 16:18:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 16:18:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 16:18:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0307', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 16:18:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0307 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 16:18:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.NMBPRSBhxD/Render', 16:18:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 16:18:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 16:18:34 INFO - 'HOME': '/Users/cltbld', 16:18:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 16:18:34 INFO - 'LOGNAME': 'cltbld', 16:18:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 16:18:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 16:18:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 16:18:34 INFO - 'MOZ_NO_REMOTE': '1', 16:18:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 16:18:34 INFO - 'NO_EM_RESTART': '1', 16:18:34 INFO - 'PAGER': '/bin/cat', 16:18:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 16:18:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 16:18:34 INFO - 'PWD': '/builds/slave/test', 16:18:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 16:18:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 16:18:34 INFO - 'SHELL': '/bin/bash', 16:18:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9FPM6E79ON/Listeners', 16:18:34 INFO - 'TMPDIR': '/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/', 16:18:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 16:18:34 INFO - 'USER': 'cltbld', 16:18:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 16:18:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 16:18:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 16:18:34 INFO - 'XPC_FLAGS': '0x0', 16:18:34 INFO - 'XPC_SERVICE_NAME': '0', 16:18:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 16:18:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0307', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 16:18:35 INFO - mozversion application_buildid: 20161022135722 16:18:35 INFO - mozversion application_changeset: 60dd82380d43a2b681f50842238f829204486290 16:18:35 INFO - mozversion application_display_name: Nightly 16:18:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 16:18:35 INFO - mozversion application_name: Firefox 16:18:35 INFO - mozversion application_remotingname: firefox 16:18:35 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 16:18:35 INFO - mozversion application_vendor: Mozilla 16:18:35 INFO - mozversion application_version: 52.0a1 16:18:35 INFO - mozversion platform_buildid: 20161022135722 16:18:35 INFO - mozversion platform_changeset: 60dd82380d43a2b681f50842238f829204486290 16:18:35 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 16:18:35 INFO - mozversion platform_version: 52.0a1 16:18:35 INFO - using testdate: 1477178315 16:18:35 INFO - actual date: 1477178315 16:18:35 INFO - starting webserver on 'localhost:49275' 16:18:35 INFO - SUITE-START | Running 2 tests 16:18:35 INFO - TEST-START | dromaeo_css 16:18:35 INFO - operating with platform_type : mac_ 16:18:35 INFO - Initialising browser for dromaeo_css test... 16:18:35 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpmm8T1E/profile http://localhost:49275/getInfo.html) 16:18:38 INFO - PROCESS | 1657 | 2016-10-22 16:18:38.184 plugin-container[1660:8285] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 16:18:38 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:18:38 INFO - PROCESS | 1657 | 2016-10-22 16:18:38.188 plugin-container[1660:8285] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 16:18:38 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:18:38 INFO - PROCESS | 1657 | 2016-10-22 16:18:38.188 plugin-container[1660:8285] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:18:38 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 16:18:38 INFO - PROCESS | 1657 | colorDepth:24 16:18:38 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 16:18:38 INFO - PROCESS | 1657 | __metrics 16:18:38 ERROR - PROCESS | 1657 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 16:18:39 INFO - TEST-INFO | 1657: exit 0 16:18:39 INFO - Browser initialized. 16:18:39 INFO - Running cycle 1/1 for dromaeo_css test... 16:18:39 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpmm8T1E/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:18:41 INFO - PROCESS | 1661 | 2016-10-22 16:18:41.360 plugin-container[1662:8399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 16:18:41 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:18:41 INFO - PROCESS | 1661 | 2016-10-22 16:18:41.360 plugin-container[1662:8399] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 16:18:41 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:18:41 INFO - PROCESS | 1661 | 2016-10-22 16:18:41.360 plugin-container[1662:8399] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:18:51 INFO - PROCESS | 1661 | RSS: Main: 236449792 16:18:51 INFO - PROCESS | 1661 | 16:19:40 INFO - PROCESS | 1661 | 1477178380728 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] 16:21:53 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 16:21:55 INFO - PROCESS | 1661 | RSS: Main: 250732544 16:21:55 INFO - PROCESS | 1661 | 16:24:57 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 16:24:58 INFO - PROCESS | 1661 | RSS: Main: 263356416 16:24:58 INFO - PROCESS | 1661 | 16:27:59 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 16:28:00 INFO - PROCESS | 1661 | RSS: Main: 268095488 16:28:00 INFO - PROCESS | 1661 | 16:31:02 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 16:31:03 INFO - PROCESS | 1661 | RSS: Main: 267649024 16:31:03 INFO - PROCESS | 1661 | 16:34:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 16:34:06 INFO - PROCESS | 1661 | RSS: Main: 272797696 16:34:06 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 16:37:09 INFO - PROCESS | 1661 | RSS: Main: 275742720 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | __start_tp_report 16:37:09 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 16:37:09 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 16:37:09 INFO - PROCESS | 1661 | |i|pagename|runs| 16:37:09 INFO - PROCESS | 1661 | |0;dojo.html;2826.3923256499847;3338.9999999999995;3339.5992480902282;3383.035834787086;3387.1870751019746;4503.977480112599;5073.644844860852;5409.729513524328;5513.310836145477;5590.385057643661;5377.8117765878205;5458.6724796512235;5461.126219804832;5520.806771762989;5556.138798486231;5113.846584602468;5152.896942061157;5161.535461808437;5195.480451954793;5229.424763276055;5070;5083.745812709368;5141.434442211354;5164.92252616211;5209.036328279261;5181.455947125554;5213.661112027706;5236.423993360727;5261.184516399945;5269.235960785683;4989.700617962933;5076.390833100015;5088.949110508885;5118.667286626357;5122.564582010534;5028.270900719375;5040.7731652075745;5092.981403719271;5123.052235336475;5126.71803050832;4426.911461770779;5379.246905433203;5438.83683489496;5462.289902312674;5503.7798488060425;5996.730147143389;6016.398360163992;6026.728797204137;6037.094435834616;6041.667708276087;6049.697515124226;6114.510839132903;6116.357782432825;6121.326654068049;6135.601185922901;5596.720163991784;5597.888042239173;5609.551235901118;5623.803166889139;5624.55003599711;6089.786857460056;6209.565330426827;6229.1590635264565;6236.6258024518675;6241.438270555672;5828.88342233153;5862.14998825168;5878.882422351529;5896.174535565025;5984.640921544721;6094.024956007018;6101.145839582462;6108.419700128481;6110.847228819226;6119.357467465941;5825.126231065289;6012;6031.457168854737;6038.7282572283475;6048.637081775108;5910.024845900378;5971;6005.549583781234;6022.2171117754415;6023.427774361428;4796.616270698406;4811.422629284508;4823.059503396874;4823.469418363977;4858.5384388483035;4352.542982986804;4361.0841723237845;4378.365137055109;4382.846600368972;4405.07493426384;5514.944850551524;5517.779288828482;5595;5602.38373778884;5613.382527921925;4518;4978.701277923336;5136.9743151284;5973.014452615356;6059.36376680451;4693.78877950493;5628.240187574625;5637;5638.210650508932;5688.943110568925;4372.628326592212;5162.793488260428;5222.164453687392;5264.52619264268;5277.129273669878;5166;6211.192544969125;6223.626582405071;6226.875462490815;6243.406876346739;4689.179393606173;6114.6636934969;6121.479674227562;6127.8663447260615;6130.233720784902;6086.6348019119;6143.109249158758;6148.139260503443;6181.938180618136;6201.56589038781;5942.702864856827;6014.488768454556;6019.608725432833;6047.78832740852;6057.454829065406;6057.606255593372;6068.7269072892705;6073.720191096758;6079.604825686316;6086.908696369646;5685.488306052475;5700.857478563069;5912.556558258234;5952.851178720567;5968.940310596839;5808.390119037609;5817.912731308949;5849.093390524476;5865.090910908816;5966.045432730916;5577.219189313419;5773;5783.826485205451;5789.131630255327;5790.62360946554;5679.119736440858;5715.942840571541;5774.509166720875;5785.016547187072;5919.881602368015;5739.971300143473;5747.36778954323;5748.137779333133;5778.104393818965;5789.681567513827 16:37:09 INFO - PROCESS | 1661 | |1;ext.html;474.54917828063327;483.7750446042594;494.473385844076;495.7199182461909;529.862235818687;40120.598794012054;41799.164016719646;41922;44212.55787442125;44271.77864110683;42772.358414623726;43190.92022699426;43353.78323108388;43368.566314336844;43496.565034349645;7271.89092163618;7710.57591832449;8278.130796266396;9457.527123643824;9715.61137554497;7735.2264773522475;8461.857649217369;9590.280728945321;9642.228621710281;9655.420674759502;9387.812243755121;9526.80946381072;9648.5175741213;9655.13103820656;9661.4203147811;9029.729108126767;9463;9597;9604.135627793463;9641.180499657537;9541.379810312308;9599.4720290384;9605.279604029725;9626.326157169002;9638.518074096268;40527;40639.984000399934;40849.591504085176;40864.591354086675;40898.79550602258;3350.2461946062185;3586.37238483267;3786.6970642348547;3937.3503371943593;4060.593940605945;30210;31302.686973130203;31472.685273147203;31606.683933160602;31917;3220.855061522237;3248.480243161083;3252.886148984774;3302.488114342281;3351.028201821469;441099;449442;453599;454009.7299513514;454410;57425.42574574285;57628.42371576314;57672.13491797626;57724.71137644285;57794.7110264446;61330.38669613247;61473.692631536556;61611.383886161464;61688;61781;15406.306716197798;15647.608809779618;15802.288896999433;15850.92074539643;15883;12179.330136842558;12280.508779648893;12421.689457763447;12426.378681065911;12469.942459494263;15481.458148964957;15698.293576789072;15781.447649332447;15830;15861.603459913595;3150.637676667167;5699.373068962411;6608.702608382634;6726.495512836557;6799.252082270947;868.5657171414293;988.9505524723735;1025.261811495722;1038.050184081572;1049.2812423489934;26984.59523107155;27688.892444302404;30655.386892262475;30671;30911.2272193197;1039.8648175737105;1071.4696225368493;1086.4079076903106;1104.2601457023814;1176.4941075337688;12359;14034.157950523002;14718.748906342867;15516.689666206612;16286.02283862972;484508;620777;623991;626579;627116;58646.7067664659;59304;59767.011649417356;59854;59988;1186.300082951063;1335.7795963665885;1373.9656508587366;1384.7922811578344;1392.0812263905975;26062.478750425264;27334.726652733218;29516.852415737783;29680.851595741882;30747.077587672407;52952.20571691351;54356.728216358664;54679.45320546903;57205.713971429876;61172.08241876464;446648;448389;450993;453388;453618;42621.147577048905;42917;43108.56891431171;43527.347089793046;43697.34453983129;47668.52331476641;47813;47833.76083119562;48128;49173.2789352371;29459.705402946554;39558;40683.77948661545;40691.389629156176;41389.58610413978;38682.419763703;38966.6103338963;39216.803915980236;40055.79972100121;40277.597224028556;38032.61967380291;38556;39320.41019384655;39402;39488;31377.529337059506;34760.304793904485;34925.126871828405;35111.297774043866;35663;21746.89126554357;25807.612885807102;25823.604669758246;25901.09346172875;25918.468295169045 16:37:09 INFO - PROCESS | 1661 | |2;jquery.html;340.34143931492565;359.5361983041878;366.0373218435514;375.2813362410983;392.32324240684824;18594.884306941593;19173.616527669437;20234.59530809385;20424.795752042475;20449.182032718672;16404;19893;20358.491037724063;20758.27346042889;20829.47926301843;5585.2459917910965;5606.439356064391;5820.243368362107;5865.501432378253;5887.941120588804;5619.213310136584;5816.709164541777;5822.912656310137;5829.795957141501;5838.474537291643;4938.38270216223;5194.662346947455;5683.175939488771;5766.760146568487;5768.451997060272;6430.871382572346;6599.47204223661;6599.835004124887;6602.2407423146;6620.205575330967;5657.858553536194;5807.622504537191;5819.563532735019;5823.505002074829;5852.912206316909;12874.42065107063;13223.471061157543;13275.86724132756;13437.924966002696;13461.326933653278;2212.0598745533243;2215.6898034275214;2367.7987371073477;2372.5136347048815;2847.4732174547776;9791.118799308026;10106.848397274047;10364;10484.318519296221;14887.255637218204;1851.4260579220486;1855.8051404602593;1887.6602211601905;1889.6126294109674;1906.8760530565469;118080.40959795233;120255.3987230067;122515.48680309318;122750.38624806819;125516;11280.492377842853;11489.597864074718;11514.539418423337;11537.807816514834;11737.471813768401;14359.569212923629;14387.856121438861;14416.351264193136;14416.783748243784;14432.278386080863;8166.836663266701;8428.747137585882;8452.704155354657;8491.532965686947;8574.657013719507;8296.502209867427;8368.623411946477;8372.618517944593;8487.830243395221;8555.187257210555;4391.209582275157;4507.143642707875;4525.524819893863;4585.679002469795;4604.424446944132;1885.227056906689;1931.7971612980434;1950.405126436422;1977.5945931084093;1994.4914046917943;844.3835999720238;897.5108565831638;905.8314773941673;974.7465658928588;1041.5052849896238;17029.40397086096;20115.597688046157;20333.08501117453;20954.057067432;20990.370288891052;882.969096081634;907.7821322882461;950.3369104067881;955.9808803823885;970.2916870684298;8785.601678942072;8963.775905602412;11113.499892504695;11187.272827266203;11214.383208923424;549762;560939.7812043736;564985;574570;576563;96635;104530.7524258504;118892;130357;132139;901.6483571407157;995.9153471954965;1023.2070145637042;1050.2438244463974;1053.9051485366354;14325.57023289303;17156.331147834804;18218.45344639663;18653.90673046626;18782.24871005199;53460.73269633627;54575;54829.90340193094;55235;55509;77018.61490692511;77284.22715772995;78502;79096;79168;10706.785864282827;11288.153388495732;11913.404329783649;12119.45462454174;12126.090543209119;13174.538891138764;13402.664933376744;13458.52895148665;13540.661483462598;13822.37799299014;8662.913370866463;11371.260868043551;11417.0866330695;11730.237534560227;11780;9241.86137207929;11120.944395277971;11402.94298528502;11472;11634.301941883514;11206.215564910379;11400.714982125513;11851.881481185077;11884.989775869148;11936.164468487123;12028.033245957231;12091.959736547782;12244.265344079384;12345.197562158432;12351.444185011516;11054.599178441784;11682.299062056303;11732.237404568676;11785.823212651989;11830.704232394259 16:37:09 INFO - PROCESS | 1661 | |3;mootools.html;1525.4279645133079;1552;1800.0999500249875;1814.2380200315865;1932.3140285198763;8034.638441270142;8287.378446616513;8504.362172837038;9117.726468205965;9134;8536.231739143475;8821.161989610993;8856.601452934618;8928.955355223232;8929.55352232389;2326.360250930995;2355.6808187415018;2356.5522550715395;2365;2368.834181607288;1982.6629472989625;2099.706041154232;2328.1269523928527;2338.754430784769;2367.7750613691674;1982.9206831726713;2046.5804510075473;2277.5786479501344;2317.0615900560215;2364.6807680963107;2289.5420915816735;2327.51122264323;2334.054707843318;2341.0518739910362;2342.601757701195;1729.910156601333;2240.6190947538958;2316.0388438797877;2322.2800931711054;2326.8720220387872;6863.450923926074;8973.596188171483;9019.639214431481;9051.321150913707;9278.072192780735;5234.607404444644;5319.255304257407;5319.973400132974;5338.5996050296;5430.484104010113;3192.920176995594;3340.46552551593;3533.381658209829;3644.4715516249994;3667.3948798448223;1025.3130402630256;1041.5677493840046;1048.8846226915034;1050.6585359758033;1069.2141276162015;11686.12354073448;11725.824112638402;11858.40707964607;11870.52517899283;11888.643340699793;4574.428196475441;4621.607163391082;4767.308740232716;4796.760161991887;4804.231322988305;9872.654457094111;10009.64966226193;10053.547590358452;10056.446895420822;10065.396076235449;5295.735213239323;5298.973505132527;5376.408595054619;5385.380681221632;5390.083685773349;4409.2724700424715;4567.2007398705755;4741.786619602126;4907.214845624683;4947.060058588857;3855.768653880776;3912.158885839558;3925.4111883217174;3987.0829709166474;4061.3704875744306;983.8783786483415;1133.9001168866187;1191.606769765975;1249.918755280922;1267.6323866078733;793.6349279331457;816.3917881178442;899.910008999108;917.9351951735954;979.0943377375899;5802.709864506758;5826.82519524415;5863.560232982543;6047.274327080779;6107.49995030513;407.7370096287937;417.7535254199976;425.27702905061517;441.1948194544904;448.2805097817955;3894.065424298148;3954.6440820326306;4010.839566417369;4105.4252404663375;4110.445089912883;8810.86783698245;10750.301230419998;10861.07680847121;10904.182186336056;10906.182036347305;8744.644580090096;9184.21934135606;10656.840147397796;10829.350238985686;10838;8216;8237.670493180205;8326.916730832736;8328.958355208306;8332.458390204618;5670.829875103754;5779.624324418899;5872.207252020922;5920.230370051866;5959.702014899324;9994.550245239127;10000.399976001463;10065.949670251895;10083.798324033332;10129.392236465836;9531.332806703742;9982.401055936667;10003.199744020607;10160.136388406772;10164.288499804943;6938.5836849789175;7166.211716711262;7319.560826350436;7481.476296659182;7648.388128949782;7624.275693809301;8203.548804815793;8402;8515.27620152294;8524.74425767228;7463.962680186782;7560.243975602396;7575.810604734926;7662.118856331594;7687.654055567625;7374.520656157547;7381.778546643609;7392.371648409945;7526.435517336331;7550.848983020199;6922.550034247758;7006.2643422439905;7089.61007144612;7092.574445533284;7093.6453177341955;4600.6319494439695;4687.67186296956;4896.755162241945;5117.820876269313;5147.536721695065;5009.348784657971;5028.823991160292;5145.305383773143;5234.52889239955;5255.658382205144 16:37:09 INFO - PROCESS | 1661 | |4;prototype.html;295.8284195166803;313.01868641807926;313.12325488631825;320.0941336019065;321.41021226050196;5184.1705327147665;5370.59720520961;5687.744051517681;5716.857078573038;5772.53819694424;4367.868963931087;5122.385313762345;5578.959467090435;5587.580931430149;5635.915461268084;3491.5810102787573;4019.35690289553;4125.66994640428;4152.626263636272;4194.916101677964;3656.926861462769;4184.539700632943;4196.727212731163;4200.411942328076;4243.830246790125;4154.771487568182;4200.054987627775;4217.102303963214;4229.577042295761;4233.597808208231;3125.812451252932;4016.6786657067655;4081.6122468365606;4181.665466762682;4216.704830661855;3463.5497385339745;3643.580988186366;3646.2342907989355;3667.193217492174;3668.358037343481;4526.4794548627;4652.55800698933;5065;5376.005438993799;5407.324084489439;3638.727095467824;3961.98019009906;4001.7198796084285;4015.678745700337;4841.346418233528;4720.763961801897;5444.265024221719;5808.622439541458;5808.651480911159;5835.79574714883;3641.85432582699;3710.886733979822;3903.2193561287854;3908.7654740715648;3950.822213000422;5137.48625137487;5213.296205012312;5434.375046869621;5968.194293770329;6173.789629392735;3383.780054296463;3889.2999260132883;3928.2929072767174;3939.586343433898;3953.2686453006286;3102.3640153768424;3928.194720082376;4030.778307193074;4075;4075.2460794753065;4042.3330150524835;4546.657941665701;4790.952090479121;4791.401074865642;4796.688215265996;5441.646292990943;5871.794487193013;5883.588148829541;5982.670953097534;6037.577369584087;1360.6462319796924;1372.5588132460875;1372.8627137286192;1380.758367285721;1383.4673650644575;722.8409749855024;746.3991486853135;785;846.611557046432;855.2388374346837;4411.823527058946;5185.440417871396;5655.802046928337;5676.545876329884;5688.317401911714;5782.508486778587;6218.284897236786;6233.0338797486465;6233.688315584202;6239.097075391958;4699.483056863743;5329.360476742816;5585.609007369527;5659.094544872801;5676.687782171937;3199.8720051197693;3235.498497732855;3236.4012657657936;3260.0382887048377;3268;5516.448355164452;5944.227250457413;6353.047042943595;6413.146149963315;6455.61266324022;5633.295838020247;6056.878862422727;6214.564980451415;6268.655223962634;6283.246010478681;5662.7734890603915;5704.942950570441;5784.537237021028;5882.999890018707;6431.646259455775;4838.879028024327;4987.830293822199;5777.16231146473;6110.236220472441;6206.72069756871;5066.898662026812;5464.125739881759;5479.890402191854;5624.690642014729;6016.4885984690045;5350.234422640597;6430.42782886849;6470.773522926675;6479.5788273762055;6488.643124628191;3983.143624120828;4119.475793956136;4207.347861081537;4221.409002739681;4224.1340525191645;6143.72353244114;6208.658523781055;6229.003359462465;6229.408206220402;6270.874582508233;5677.74450149736;5727.656340619576;5767.307923049261;5924.7037648118285;6057.242844644419;5947.553933455095;5959.553033522417;5972.761089556369;6010.54920880927;6012.308584512837;4476.58987418962;5065.13892638245;5699.632088298861;5887.614621592687;5905.852353691021;5431.802861208993;5464.535514481155;5656.935218645247;5906.173135761082;5969.8806023878415;5089.862257851637;5251.501107394792;5803.303603567599;5857.179994800646;5863.618864773776 16:37:09 INFO - PROCESS | 1661 | |5;yui.html;641;733.7982054934893;794.8291117409759;796.474326944217;822.5804839531841;15351.69296614067;15660.608484787857;15883.523494295176;16279.104649244287;16303.429379971674;14742.336594853257;14810.629734256649;14886.404543818262;14887.18120503372;14892.329845156966;704.7885634309712;722.4292808681136;830.1905641999062;863.0377129500624;890.0876601483467;754.4605606991;898.9235914947237;901.4546199549277;914.1361413464278;918.0956757593765;955.7371722776222;966.5505539923934;966.6906589891238;967.7677357434236;969.2585172343163;940.8588711693267;951.6145960885783;955.6894009446888;956.9521523923776;959.5490119643756;698.1691786773723;717.5049216040915;720.8125887269296;722.3859719238658;722.8120688620996;14716;14898.031627944258;14925.701485970329;14945.476908308267;14975.400983960628;14549;14579.562613121623;14628.268586570628;14630.341634626468;14710;11830.467628956632;12019.759604807943;12225.633231002996;12257.877421225763;12484.687882802911;3733.663970242691;3766.8116594170456;3916.784576848272;3957.0898693300414;3957.703172262092;115837.42081289539;116022;116458;117767;124903.75096249011;9414.576344064533;11075.837037111149;11113.499892504857;11128.276662016942;11657.475413606408;22555.661665075037;23138.884305578365;23254.209494860388;23271.7672823273;23406;7854.057513098464;7994.68021279154;8049.557274349967;8103.270705636521;8193.50838949665;4306.569343065668;4370.322599997005;4385.561443855589;4421.403110580095;4508.549145085531;3478.913027174341;3572.7856328620364;3587.892363229107;3599.2441587266967;3606.0804494853646;649.4998850884792;650.2489624483729;665.710415969055;681.9795406137824;685.3831551603597;645.7255666341786;654.6759354119741;664.8072059102902;666.1373521289942;686.704716971707;9250.629974801068;9351.524005249186;9402.576884040098;9500.619975201053;9558.665446709332;567.6707509644397;660.7125900233414;665.950053745971;682.6006586847137;691.1464341538197;4323.1785960668;4433.866983990485;4678.836240731558;4745.6440766942615;4782.426108866958;106920.46539767251;115978;162926;162934.18532907258;165382;108755.91244087616;108937.45531272293;111159;122938;136991.63008369989;73534.26465735275;74406.76779696617;89991;99402.5029874846;114960;9052.3210759192;9697.709068727949;9910.801783964425;9966.651167209113;10007.899921000988;62509;62571.687141564;63740.777666999005;64795.67602161959;65525.37898484198;60670;62536;62782.372176277655;84862;86096.52125676493;13315.66710832237;13327.733445331232;13381.459459729658;13704.588862334145;13762.449502020208;13697.452101915811;13897.444102235797;14535.636609084857;14636.634084147981;14800.333984970486;11864.525418983145;13576;13604.727905442034;14384.06503577264;14413.9279303607;13532.93233533826;13730.794038089236;13749.106308089942;13888.08925859377;13904.791428128789;13228.07403481747;13620.523281685093;13659.521916732867;13709.862901370858;13774.242416666779;9112;11106;11381;11398.715032124263;11585.304881707125;8359.414840961317;8719.564021799011;10801.513931872927;11049.83425248638;11447.656570302905 16:37:09 INFO - PROCESS | 1661 | __end_tp_report 16:37:09 INFO - PROCESS | 1661 | __start_cc_report 16:37:09 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,363 16:37:09 INFO - PROCESS | 1661 | __end_cc_report 16:37:09 INFO - PROCESS | 1661 | __startTimestamp1477179429558__endTimestamp 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | ------- Summary: start ------- 16:37:09 INFO - PROCESS | 1661 | Number of tests: 6 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | [#0] dojo.html Cycles:165 Average:5530.32 Median:5688.94 stddev:637.65 (11.2%) stddev-sans-first:603.29 16:37:09 INFO - PROCESS | 1661 | Values: 2826.4 3339.0 3339.6 3383.0 3387.2 4504.0 5073.6 5409.7 5513.3 5590.4 5377.8 5458.7 5461.1 5520.8 5556.1 5113.8 5152.9 5161.5 5195.5 5229.4 5070.0 5083.7 5141.4 5164.9 5209.0 5181.5 5213.7 5236.4 5261.2 5269.2 4989.7 5076.4 5088.9 5118.7 5122.6 5028.3 5040.8 5093.0 5123.1 5126.7 4426.9 5379.2 5438.8 5462.3 5503.8 5996.7 6016.4 6026.7 6037.1 6041.7 6049.7 6114.5 6116.4 6121.3 6135.6 5596.7 5597.9 5609.6 5623.8 5624.6 6089.8 6209.6 6229.2 6236.6 6241.4 5828.9 5862.1 5878.9 5896.2 5984.6 6094.0 6101.1 6108.4 6110.8 6119.4 5825.1 6012.0 6031.5 6038.7 6048.6 5910.0 5971.0 6005.5 6022.2 6023.4 4796.6 4811.4 4823.1 4823.5 4858.5 4352.5 4361.1 4378.4 4382.8 4405.1 5514.9 5517.8 5595.0 5602.4 5613.4 4518.0 4978.7 5137.0 5973.0 6059.4 4693.8 5628.2 5637.0 5638.2 5688.9 4372.6 5162.8 5222.2 5264.5 5277.1 5166.0 6211.2 6223.6 6226.9 6243.4 4689.2 6114.7 6121.5 6127.9 6130.2 6086.6 6143.1 6148.1 6181.9 6201.6 5942.7 6014.5 6019.6 6047.8 6057.5 6057.6 6068.7 6073.7 6079.6 6086.9 5685.5 5700.9 5912.6 5952.9 5968.9 5808.4 5817.9 5849.1 5865.1 5966.0 5577.2 5773.0 5783.8 5789.1 5790.6 5679.1 5715.9 5774.5 5785.0 5919.9 5740.0 5747.4 5748.1 5778.1 5789.7 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | [#1] ext.html Cycles:180 Average:64918.54 Median:41860.58 stddev:134447.24 (321.2%) stddev-sans-first:134737.33 16:37:09 INFO - PROCESS | 1661 | Values: 474.5 483.8 494.5 495.7 529.9 40120.6 41799.2 41922.0 44212.6 44271.8 42772.4 43190.9 43353.8 43368.6 43496.6 7271.9 7710.6 8278.1 9457.5 9715.6 7735.2 8461.9 9590.3 9642.2 9655.4 9387.8 9526.8 9648.5 9655.1 9661.4 9029.7 9463.0 9597.0 9604.1 9641.2 9541.4 9599.5 9605.3 9626.3 9638.5 40527.0 40640.0 40849.6 40864.6 40898.8 3350.2 3586.4 3786.7 3937.4 4060.6 30210.0 31302.7 31472.7 31606.7 31917.0 3220.9 3248.5 3252.9 3302.5 3351.0 441099.0 449442.0 453599.0 454009.7 454410.0 57425.4 57628.4 57672.1 57724.7 57794.7 61330.4 61473.7 61611.4 61688.0 61781.0 15406.3 15647.6 15802.3 15850.9 15883.0 12179.3 12280.5 12421.7 12426.4 12469.9 15481.5 15698.3 15781.4 15830.0 15861.6 3150.6 5699.4 6608.7 6726.5 6799.3 868.6 989.0 1025.3 1038.1 1049.3 26984.6 27688.9 30655.4 30671.0 30911.2 1039.9 1071.5 1086.4 1104.3 1176.5 12359.0 14034.2 14718.7 15516.7 16286.0 484508.0 620777.0 623991.0 626579.0 627116.0 58646.7 59304.0 59767.0 59854.0 59988.0 1186.3 1335.8 1374.0 1384.8 1392.1 26062.5 27334.7 29516.9 29680.9 30747.1 52952.2 54356.7 54679.5 57205.7 61172.1 446648.0 448389.0 450993.0 453388.0 453618.0 42621.1 42917.0 43108.6 43527.3 43697.3 47668.5 47813.0 47833.8 48128.0 49173.3 29459.7 39558.0 40683.8 40691.4 41389.6 38682.4 38966.6 39216.8 40055.8 40277.6 38032.6 38556.0 39320.4 39402.0 39488.0 31377.5 34760.3 34925.1 35111.3 35663.0 21746.9 25807.6 25823.6 25901.1 25918.5 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | [#2] jquery.html Cycles:180 Average:33796.72 Median:20345.79 stddev:94530.45 (464.6%) stddev-sans-first:94762.25 16:37:09 INFO - PROCESS | 1661 | Values: 340.3 359.5 366.0 375.3 392.3 18594.9 19173.6 20234.6 20424.8 20449.2 16404.0 19893.0 20358.5 20758.3 20829.5 5585.2 5606.4 5820.2 5865.5 5887.9 5619.2 5816.7 5822.9 5829.8 5838.5 4938.4 5194.7 5683.2 5766.8 5768.5 6430.9 6599.5 6599.8 6602.2 6620.2 5657.9 5807.6 5819.6 5823.5 5852.9 12874.4 13223.5 13275.9 13437.9 13461.3 2212.1 2215.7 2367.8 2372.5 2847.5 9791.1 10106.8 10364.0 10484.3 14887.3 1851.4 1855.8 1887.7 1889.6 1906.9 118080.4 120255.4 122515.5 122750.4 125516.0 11280.5 11489.6 11514.5 11537.8 11737.5 14359.6 14387.9 14416.4 14416.8 14432.3 8166.8 8428.7 8452.7 8491.5 8574.7 8296.5 8368.6 8372.6 8487.8 8555.2 4391.2 4507.1 4525.5 4585.7 4604.4 1885.2 1931.8 1950.4 1977.6 1994.5 844.4 897.5 905.8 974.7 1041.5 17029.4 20115.6 20333.1 20954.1 20990.4 883.0 907.8 950.3 956.0 970.3 8785.6 8963.8 11113.5 11187.3 11214.4 549762.0 560939.8 564985.0 574570.0 576563.0 96635.0 104530.8 118892.0 130357.0 132139.0 901.6 995.9 1023.2 1050.2 1053.9 14325.6 17156.3 18218.5 18653.9 18782.2 53460.7 54575.0 54829.9 55235.0 55509.0 77018.6 77284.2 78502.0 79096.0 79168.0 10706.8 11288.2 11913.4 12119.5 12126.1 13174.5 13402.7 13458.5 13540.7 13822.4 8662.9 11371.3 11417.1 11730.2 11780.0 9241.9 11120.9 11402.9 11472.0 11634.3 11206.2 11400.7 11851.9 11885.0 11936.2 12028.0 12092.0 12244.3 12345.2 12351.4 11054.6 11682.3 11732.2 11785.8 11830.7 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | [#3] mootools.html Cycles:180 Average:5672.50 Median:4800.50 stddev:3219.31 (67.1%) stddev-sans-first:3213.25 16:37:09 INFO - PROCESS | 1661 | Values: 1525.4 1552.0 1800.1 1814.2 1932.3 8034.6 8287.4 8504.4 9117.7 9134.0 8536.2 8821.2 8856.6 8929.0 8929.6 2326.4 2355.7 2356.6 2365.0 2368.8 1982.7 2099.7 2328.1 2338.8 2367.8 1982.9 2046.6 2277.6 2317.1 2364.7 2289.5 2327.5 2334.1 2341.1 2342.6 1729.9 2240.6 2316.0 2322.3 2326.9 6863.5 8973.6 9019.6 9051.3 9278.1 5234.6 5319.3 5320.0 5338.6 5430.5 3192.9 3340.5 3533.4 3644.5 3667.4 1025.3 1041.6 1048.9 1050.7 1069.2 11686.1 11725.8 11858.4 11870.5 11888.6 4574.4 4621.6 4767.3 4796.8 4804.2 9872.7 10009.6 10053.5 10056.4 10065.4 5295.7 5299.0 5376.4 5385.4 5390.1 4409.3 4567.2 4741.8 4907.2 4947.1 3855.8 3912.2 3925.4 3987.1 4061.4 983.9 1133.9 1191.6 1249.9 1267.6 793.6 816.4 899.9 917.9 979.1 5802.7 5826.8 5863.6 6047.3 6107.5 407.7 417.8 425.3 441.2 448.3 3894.1 3954.6 4010.8 4105.4 4110.4 8810.9 10750.3 10861.1 10904.2 10906.2 8744.6 9184.2 10656.8 10829.4 10838.0 8216.0 8237.7 8326.9 8329.0 8332.5 5670.8 5779.6 5872.2 5920.2 5959.7 9994.6 10000.4 10065.9 10083.8 10129.4 9531.3 9982.4 10003.2 10160.1 10164.3 6938.6 7166.2 7319.6 7481.5 7648.4 7624.3 8203.5 8402.0 8515.3 8524.7 7464.0 7560.2 7575.8 7662.1 7687.7 7374.5 7381.8 7392.4 7526.4 7550.8 6922.6 7006.3 7089.6 7092.6 7093.6 4600.6 4687.7 4896.8 5117.8 5147.5 5009.3 5028.8 5145.3 5234.5 5255.7 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | [#4] prototype.html Cycles:180 Average:4713.13 Median:5360.42 stddev:1499.18 (28.0%) stddev-sans-first:1466.26 16:37:09 INFO - PROCESS | 1661 | Values: 295.8 313.0 313.1 320.1 321.4 5184.2 5370.6 5687.7 5716.9 5772.5 4367.9 5122.4 5579.0 5587.6 5635.9 3491.6 4019.4 4125.7 4152.6 4194.9 3656.9 4184.5 4196.7 4200.4 4243.8 4154.8 4200.1 4217.1 4229.6 4233.6 3125.8 4016.7 4081.6 4181.7 4216.7 3463.5 3643.6 3646.2 3667.2 3668.4 4526.5 4652.6 5065.0 5376.0 5407.3 3638.7 3962.0 4001.7 4015.7 4841.3 4720.8 5444.3 5808.6 5808.7 5835.8 3641.9 3710.9 3903.2 3908.8 3950.8 5137.5 5213.3 5434.4 5968.2 6173.8 3383.8 3889.3 3928.3 3939.6 3953.3 3102.4 3928.2 4030.8 4075.0 4075.2 4042.3 4546.7 4791.0 4791.4 4796.7 5441.6 5871.8 5883.6 5982.7 6037.6 1360.6 1372.6 1372.9 1380.8 1383.5 722.8 746.4 785.0 846.6 855.2 4411.8 5185.4 5655.8 5676.5 5688.3 5782.5 6218.3 6233.0 6233.7 6239.1 4699.5 5329.4 5585.6 5659.1 5676.7 3199.9 3235.5 3236.4 3260.0 3268.0 5516.4 5944.2 6353.0 6413.1 6455.6 5633.3 6056.9 6214.6 6268.7 6283.2 5662.8 5704.9 5784.5 5883.0 6431.6 4838.9 4987.8 5777.2 6110.2 6206.7 5066.9 5464.1 5479.9 5624.7 6016.5 5350.2 6430.4 6470.8 6479.6 6488.6 3983.1 4119.5 4207.3 4221.4 4224.1 6143.7 6208.7 6229.0 6229.4 6270.9 5677.7 5727.7 5767.3 5924.7 6057.2 5947.6 5959.6 5972.8 6010.5 6012.3 4476.6 5065.1 5699.6 5887.6 5905.9 5431.8 5464.5 5656.9 5906.2 5969.9 5089.9 5251.5 5803.3 5857.2 5863.6 16:37:09 INFO - PROCESS | 1661 | 16:37:09 INFO - PROCESS | 1661 | [#5] yui.html Cycles:180 Average:23646.40 Median:3936.94 stddev:37311.68 (947.7%) stddev-sans-first:37376.37 16:37:09 INFO - PROCESS | 1661 | Values: 641.0 733.8 794.8 796.5 822.6 15351.7 15660.6 15883.5 16279.1 16303.4 14742.3 14810.6 14886.4 14887.2 14892.3 704.8 722.4 830.2 863.0 890.1 754.5 898.9 901.5 914.1 918.1 955.7 966.6 966.7 967.8 969.3 940.9 951.6 955.7 957.0 959.5 698.2 717.5 720.8 722.4 722.8 14716.0 14898.0 14925.7 14945.5 14975.4 14549.0 14579.6 14628.3 14630.3 14710.0 11830.5 12019.8 12225.6 12257.9 12484.7 3733.7 3766.8 3916.8 3957.1 3957.7 115837.4 116022.0 116458.0 117767.0 124903.8 9414.6 11075.8 11113.5 11128.3 11657.5 22555.7 23138.9 23254.2 23271.8 23406.0 7854.1 7994.7 8049.6 8103.3 8193.5 4306.6 4370.3 4385.6 4421.4 4508.5 3478.9 3572.8 3587.9 3599.2 3606.1 649.5 650.2 665.7 682.0 685.4 645.7 654.7 664.8 666.1 686.7 9250.6 9351.5 9402.6 9500.6 9558.7 567.7 660.7 666.0 682.6 691.1 4323.2 4433.9 4678.8 4745.6 4782.4 106920.5 115978.0 162926.0 162934.2 165382.0 108755.9 108937.5 111159.0 122938.0 136991.6 73534.3 74406.8 89991.0 99402.5 114960.0 9052.3 9697.7 9910.8 9966.7 10007.9 62509.0 62571.7 63740.8 64795.7 65525.4 60670.0 62536.0 62782.4 84862.0 86096.5 13315.7 13327.7 13381.5 13704.6 13762.4 13697.5 13897.4 14535.6 14636.6 14800.3 11864.5 13576.0 13604.7 14384.1 14413.9 13532.9 13730.8 13749.1 13888.1 13904.8 13228.1 13620.5 13659.5 13709.9 13774.2 9112.0 11106.0 11381.0 11398.7 11585.3 8359.4 8719.6 10801.5 11049.8 11447.7 16:37:09 INFO - PROCESS | 1661 | -------- Summary: end -------- 16:37:09 INFO - PROCESS | 1661 | 16:37:10 INFO - TEST-INFO | 1661: exit 0 16:37:10 INFO - TEST-OK | dromaeo_css | took 1114980ms 16:37:10 INFO - TEST-START | kraken 16:37:10 INFO - operating with platform_type : mac_ 16:37:10 INFO - Initialising browser for kraken test... 16:37:10 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpeQvigp/profile http://localhost:49275/getInfo.html) 16:37:12 INFO - PROCESS | 1669 | 2016-10-22 16:37:12.786 plugin-container[1670:10529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893b, name = 'com.apple.tsm.portname' 16:37:12 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:37:12 INFO - PROCESS | 1669 | 2016-10-22 16:37:12.790 plugin-container[1670:10529] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 16:37:12 INFO - PROCESS | 1669 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:37:12 INFO - PROCESS | 1669 | 2016-10-22 16:37:12.790 plugin-container[1670:10529] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:37:12 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 16:37:12 INFO - PROCESS | 1669 | colorDepth:24 16:37:12 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 16:37:12 INFO - PROCESS | 1669 | __metrics 16:37:12 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 16:37:13 INFO - TEST-INFO | 1669: exit 0 16:37:13 INFO - Browser initialized. 16:37:13 INFO - Running cycle 1/1 for kraken test... 16:37:13 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/tmpeQvigp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 16:37:16 INFO - PROCESS | 1671 | 2016-10-22 16:37:16.067 plugin-container[1672:10643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 16:37:16 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:37:16 INFO - PROCESS | 1671 | 2016-10-22 16:37:16.067 plugin-container[1672:10643] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4523, name = 'com.apple.CFPasteboardClient' 16:37:16 INFO - PROCESS | 1671 | See /usr/include/servers/bootstrap_defs.h for the error codes. 16:37:16 INFO - PROCESS | 1671 | 2016-10-22 16:37:16.067 plugin-container[1672:10643] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 16:37:25 INFO - PROCESS | 1671 | RSS: Main: 236847104 16:37:25 INFO - PROCESS | 1671 | 16:37:29 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 16:37:30 INFO - PROCESS | 1671 | RSS: Main: 249360384 16:37:30 INFO - PROCESS | 1671 | 16:37:32 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 16:37:33 INFO - PROCESS | 1671 | RSS: Main: 256868352 16:37:33 INFO - PROCESS | 1671 | 16:37:36 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 16:37:37 INFO - PROCESS | 1671 | RSS: Main: 259768320 16:37:37 INFO - PROCESS | 1671 | 16:37:39 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 16:37:40 INFO - PROCESS | 1671 | RSS: Main: 261873664 16:37:40 INFO - PROCESS | 1671 | 16:37:42 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 16:37:42 INFO - PROCESS | 1671 | RSS: Main: 262234112 16:37:42 INFO - PROCESS | 1671 | 16:37:45 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 16:37:46 INFO - PROCESS | 1671 | RSS: Main: 262492160 16:37:46 INFO - PROCESS | 1671 | 16:37:49 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 16:37:50 INFO - PROCESS | 1671 | RSS: Main: 262725632 16:37:50 INFO - PROCESS | 1671 | 16:37:53 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 16:37:53 INFO - PROCESS | 1671 | RSS: Main: 262864896 16:37:53 INFO - PROCESS | 1671 | 16:37:55 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 16:37:55 INFO - PROCESS | 1671 | RSS: Main: 262975488 16:37:55 INFO - PROCESS | 1671 | 16:37:57 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 16:37:58 INFO - PROCESS | 1671 | RSS: Main: 263221248 16:37:58 INFO - PROCESS | 1671 | 16:38:00 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 16:38:01 INFO - PROCESS | 1671 | RSS: Main: 263053312 16:38:01 INFO - PROCESS | 1671 | 16:38:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 16:38:05 INFO - PROCESS | 1671 | RSS: Main: 263168000 16:38:05 INFO - PROCESS | 1671 | 16:38:08 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 16:38:08 INFO - PROCESS | 1671 | RSS: Main: 262955008 16:38:08 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 16:38:10 INFO - PROCESS | 1671 | RSS: Main: 264646656 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | __start_tp_report 16:38:10 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 16:38:10 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 16:38:10 INFO - PROCESS | 1671 | |i|pagename|runs| 16:38:10 INFO - PROCESS | 1671 | |0;ai-astar;155;154;173;95;164;174;96;177;177;170 16:38:10 INFO - PROCESS | 1671 | |1;audio-beat-detection;150;117;157;168;116;116;117;119;162;148 16:38:10 INFO - PROCESS | 1671 | |2;audio-dft;224;199;200;195;242;259;200;201;201;193 16:38:10 INFO - PROCESS | 1671 | |3;audio-fft;73;85;51;85;76;53;50;59;107;85 16:38:10 INFO - PROCESS | 1671 | |4;audio-oscillator;105;103;121;103;74;70;87;71;69;90 16:38:10 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;90;90;106;107;90;87;90;88;89;87 16:38:10 INFO - PROCESS | 1671 | |6;imaging-darkroom;151;90;147;158;153;119;96;96;107;153 16:38:10 INFO - PROCESS | 1671 | |7;imaging-desaturate;88;90;86;98;91;86;89;125;76;139 16:38:10 INFO - PROCESS | 1671 | |8;json-parse-financial;57;57;55;58;57;62;51;56;58;58 16:38:10 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;79;93;77;78;75;70;70;70;75;72 16:38:10 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;90;99;109;109;86;91;87;89;91;93 16:38:10 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;160;166;148;150;158;161;167;165;226;143 16:38:10 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;176;177;171;178;173;172;171;179;175;173 16:38:10 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;71;71;71;69;72;94;90;87;72;69 16:38:10 INFO - PROCESS | 1671 | __end_tp_report 16:38:10 INFO - PROCESS | 1671 | __start_cc_report 16:38:10 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,947 16:38:10 INFO - PROCESS | 1671 | __end_cc_report 16:38:10 INFO - PROCESS | 1671 | __startTimestamp1477179490944__endTimestamp 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | ------- Summary: start ------- 16:38:10 INFO - PROCESS | 1671 | Number of tests: 14 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:153.50 Median:175.50 stddev:31.66 (18.0%) stddev-sans-first:33.57 16:38:10 INFO - PROCESS | 1671 | Values: 155.0 154.0 173.0 95.0 164.0 174.0 96.0 177.0 177.0 170.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:149.00 stddev:21.81 (14.6%) stddev-sans-first:22.62 16:38:10 INFO - PROCESS | 1671 | Values: 150.0 117.0 157.0 168.0 116.0 116.0 117.0 119.0 162.0 148.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:211.40 Median:201.00 stddev:22.60 (11.2%) stddev-sans-first:23.51 16:38:10 INFO - PROCESS | 1671 | Values: 224.0 199.0 200.0 195.0 242.0 259.0 200.0 201.0 201.0 193.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:72.40 Median:74.50 stddev:18.86 (25.3%) stddev-sans-first:20.01 16:38:10 INFO - PROCESS | 1671 | Values: 73.0 85.0 51.0 85.0 76.0 53.0 50.0 59.0 107.0 85.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:89.30 Median:70.50 stddev:18.22 (25.8%) stddev-sans-first:18.41 16:38:10 INFO - PROCESS | 1671 | Values: 105.0 103.0 121.0 103.0 74.0 70.0 87.0 71.0 69.0 90.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:92.40 Median:89.50 stddev:7.53 (8.4%) stddev-sans-first:7.94 16:38:10 INFO - PROCESS | 1671 | Values: 90.0 90.0 106.0 107.0 90.0 87.0 90.0 88.0 89.0 87.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:127.00 Median:155.50 stddev:27.98 (18.0%) stddev-sans-first:28.29 16:38:10 INFO - PROCESS | 1671 | Values: 151.0 90.0 147.0 158.0 153.0 119.0 96.0 96.0 107.0 153.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:88.50 stddev:19.61 (22.2%) stddev-sans-first:20.54 16:38:10 INFO - PROCESS | 1671 | Values: 88.0 90.0 86.0 98.0 91.0 86.0 89.0 125.0 76.0 139.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:56.90 Median:57.50 stddev:2.77 (4.8%) stddev-sans-first:2.93 16:38:10 INFO - PROCESS | 1671 | Values: 57.0 57.0 55.0 58.0 57.0 62.0 51.0 56.0 58.0 58.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:75.90 Median:76.00 stddev:6.90 (9.1%) stddev-sans-first:7.23 16:38:10 INFO - PROCESS | 1671 | Values: 79.0 93.0 77.0 78.0 75.0 70.0 70.0 70.0 75.0 72.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:94.40 Median:90.50 stddev:8.47 (9.4%) stddev-sans-first:8.84 16:38:10 INFO - PROCESS | 1671 | Values: 90.0 99.0 109.0 109.0 86.0 91.0 87.0 89.0 91.0 93.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:164.40 Median:163.00 stddev:23.12 (14.2%) stddev-sans-first:24.47 16:38:10 INFO - PROCESS | 1671 | Values: 160.0 166.0 148.0 150.0 158.0 161.0 167.0 165.0 226.0 143.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:175.50 stddev:2.92 (1.7%) stddev-sans-first:3.04 16:38:10 INFO - PROCESS | 1671 | Values: 176.0 177.0 171.0 178.0 173.0 172.0 171.0 179.0 175.0 173.0 16:38:10 INFO - PROCESS | 1671 | 16:38:10 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.60 Median:72.00 stddev:9.67 (13.4%) stddev-sans-first:10.05 16:38:10 INFO - PROCESS | 1671 | Values: 71.0 71.0 71.0 69.0 72.0 94.0 90.0 87.0 72.0 69.0 16:38:10 INFO - PROCESS | 1671 | -------- Summary: end -------- 16:38:10 INFO - PROCESS | 1671 | 16:38:11 INFO - TEST-INFO | 1671: exit 0 16:38:11 INFO - TEST-OK | kraken | took 61666ms 16:38:11 INFO - SUITE-END | took 1176s 16:38:12 INFO - Completed test suite (00:19:36) 16:38:12 INFO - javascript benchmark 16:38:12 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7318.601657837795, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2826.3923256499847, 3338.9999999999995, 3339.5992480902282, 3383.035834787086, 3387.1870751019746, 4503.977480112599, 5073.644844860852, 5409.729513524328, 5513.310836145477, 5590.385057643661, 5377.8117765878205, 5458.6724796512235, 5461.126219804832, 5520.806771762989, 5556.138798486231, 5113.846584602468, 5152.896942061157, 5161.535461808437, 5195.480451954793, 5229.424763276055, 5070.0, 5083.745812709368, 5141.434442211354, 5164.92252616211, 5209.036328279261, 5181.455947125554, 5213.661112027706, 5236.423993360727, 5261.184516399945, 5269.235960785683, 4989.700617962933, 5076.390833100015, 5088.949110508885, 5118.667286626357, 5122.564582010534, 5028.270900719375, 5040.7731652075745, 5092.981403719271, 5123.052235336475, 5126.71803050832, 4426.911461770779, 5379.246905433203, 5438.83683489496, 5462.289902312674, 5503.7798488060425, 5996.730147143389, 6016.398360163992, 6026.728797204137, 6037.094435834616, 6041.667708276087, 6049.697515124226, 6114.510839132903, 6116.357782432825, 6121.326654068049, 6135.601185922901, 5596.720163991784, 5597.888042239173, 5609.551235901118, 5623.803166889139, 5624.55003599711, 6089.786857460056, 6209.565330426827, 6229.1590635264565, 6236.6258024518675, 6241.438270555672, 5828.88342233153, 5862.14998825168, 5878.882422351529, 5896.174535565025, 5984.640921544721, 6094.024956007018, 6101.145839582462, 6108.419700128481, 6110.847228819226, 6119.357467465941, 5825.126231065289, 6012.0, 6031.457168854737, 6038.7282572283475, 6048.637081775108, 5910.024845900378, 5971.0, 6005.549583781234, 6022.2171117754415, 6023.427774361428, 4796.616270698406, 4811.422629284508, 4823.059503396874, 4823.469418363977, 4858.5384388483035, 4352.542982986804, 4361.0841723237845, 4378.365137055109, 4382.846600368972, 4405.07493426384, 5514.944850551524, 5517.779288828482, 5595.0, 5602.38373778884, 5613.382527921925, 4518.0, 4978.701277923336, 5136.9743151284, 5973.014452615356, 6059.36376680451, 4693.78877950493, 5628.240187574625, 5637.0, 5638.210650508932, 5688.943110568925, 4372.628326592212, 5162.793488260428, 5222.164453687392, 5264.52619264268, 5277.129273669878, 5166.0, 6211.192544969125, 6223.626582405071, 6226.875462490815, 6243.406876346739, 4689.179393606173, 6114.6636934969, 6121.479674227562, 6127.8663447260615, 6130.233720784902, 6086.6348019119, 6143.109249158758, 6148.139260503443, 6181.938180618136, 6201.56589038781, 5942.702864856827, 6014.488768454556, 6019.608725432833, 6047.78832740852, 6057.454829065406, 6057.606255593372, 6068.7269072892705, 6073.720191096758, 6079.604825686316, 6086.908696369646, 5685.488306052475, 5700.857478563069, 5912.556558258234, 5952.851178720567, 5968.940310596839, 5808.390119037609, 5817.912731308949, 5849.093390524476, 5865.090910908816, 5966.045432730916, 5577.219189313419, 5773.0, 5783.826485205451, 5789.131630255327, 5790.62360946554, 5679.119736440858, 5715.942840571541, 5774.509166720875, 5785.016547187072, 5919.881602368015, 5739.971300143473, 5747.36778954323, 5748.137779333133, 5778.104393818965, 5789.681567513827], "value": 5492.182292591685, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [474.54917828063327, 483.7750446042594, 494.473385844076, 495.7199182461909, 529.862235818687, 40120.598794012054, 41799.164016719646, 41922.0, 44212.55787442125, 44271.77864110683, 42772.358414623726, 43190.92022699426, 43353.78323108388, 43368.566314336844, 43496.565034349645, 7271.89092163618, 7710.57591832449, 8278.130796266396, 9457.527123643824, 9715.61137554497, 7735.2264773522475, 8461.857649217369, 9590.280728945321, 9642.228621710281, 9655.420674759502, 9387.812243755121, 9526.80946381072, 9648.5175741213, 9655.13103820656, 9661.4203147811, 9029.729108126767, 9463.0, 9597.0, 9604.135627793463, 9641.180499657537, 9541.379810312308, 9599.4720290384, 9605.279604029725, 9626.326157169002, 9638.518074096268, 40527.0, 40639.984000399934, 40849.591504085176, 40864.591354086675, 40898.79550602258, 3350.2461946062185, 3586.37238483267, 3786.6970642348547, 3937.3503371943593, 4060.593940605945, 30210.0, 31302.686973130203, 31472.685273147203, 31606.683933160602, 31917.0, 3220.855061522237, 3248.480243161083, 3252.886148984774, 3302.488114342281, 3351.028201821469, 441099.0, 449442.0, 453599.0, 454009.7299513514, 454410.0, 57425.42574574285, 57628.42371576314, 57672.13491797626, 57724.71137644285, 57794.7110264446, 61330.38669613247, 61473.692631536556, 61611.383886161464, 61688.0, 61781.0, 15406.306716197798, 15647.608809779618, 15802.288896999433, 15850.92074539643, 15883.0, 12179.330136842558, 12280.508779648893, 12421.689457763447, 12426.378681065911, 12469.942459494263, 15481.458148964957, 15698.293576789072, 15781.447649332447, 15830.0, 15861.603459913595, 3150.637676667167, 5699.373068962411, 6608.702608382634, 6726.495512836557, 6799.252082270947, 868.5657171414293, 988.9505524723735, 1025.261811495722, 1038.050184081572, 1049.2812423489934, 26984.59523107155, 27688.892444302404, 30655.386892262475, 30671.0, 30911.2272193197, 1039.8648175737105, 1071.4696225368493, 1086.4079076903106, 1104.2601457023814, 1176.4941075337688, 12359.0, 14034.157950523002, 14718.748906342867, 15516.689666206612, 16286.02283862972, 484508.0, 620777.0, 623991.0, 626579.0, 627116.0, 58646.7067664659, 59304.0, 59767.011649417356, 59854.0, 59988.0, 1186.300082951063, 1335.7795963665885, 1373.9656508587366, 1384.7922811578344, 1392.0812263905975, 26062.478750425264, 27334.726652733218, 29516.852415737783, 29680.851595741882, 30747.077587672407, 52952.20571691351, 54356.728216358664, 54679.45320546903, 57205.713971429876, 61172.08241876464, 446648.0, 448389.0, 450993.0, 453388.0, 453618.0, 42621.147577048905, 42917.0, 43108.56891431171, 43527.347089793046, 43697.34453983129, 47668.52331476641, 47813.0, 47833.76083119562, 48128.0, 49173.2789352371, 29459.705402946554, 39558.0, 40683.77948661545, 40691.389629156176, 41389.58610413978, 38682.419763703, 38966.6103338963, 39216.803915980236, 40055.79972100121, 40277.597224028556, 38032.61967380291, 38556.0, 39320.41019384655, 39402.0, 39488.0, 31377.529337059506, 34760.304793904485, 34925.126871828405, 35111.297774043866, 35663.0, 21746.89126554357, 25807.612885807102, 25823.604669758246, 25901.09346172875, 25918.468295169045], "value": 19772.962020605468, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [340.34143931492565, 359.5361983041878, 366.0373218435514, 375.2813362410983, 392.32324240684824, 18594.884306941593, 19173.616527669437, 20234.59530809385, 20424.795752042475, 20449.182032718672, 16404.0, 19893.0, 20358.491037724063, 20758.27346042889, 20829.47926301843, 5585.2459917910965, 5606.439356064391, 5820.243368362107, 5865.501432378253, 5887.941120588804, 5619.213310136584, 5816.709164541777, 5822.912656310137, 5829.795957141501, 5838.474537291643, 4938.38270216223, 5194.662346947455, 5683.175939488771, 5766.760146568487, 5768.451997060272, 6430.871382572346, 6599.47204223661, 6599.835004124887, 6602.2407423146, 6620.205575330967, 5657.858553536194, 5807.622504537191, 5819.563532735019, 5823.505002074829, 5852.912206316909, 12874.42065107063, 13223.471061157543, 13275.86724132756, 13437.924966002696, 13461.326933653278, 2212.0598745533243, 2215.6898034275214, 2367.7987371073477, 2372.5136347048815, 2847.4732174547776, 9791.118799308026, 10106.848397274047, 10364.0, 10484.318519296221, 14887.255637218204, 1851.4260579220486, 1855.8051404602593, 1887.6602211601905, 1889.6126294109674, 1906.8760530565469, 118080.40959795233, 120255.3987230067, 122515.48680309318, 122750.38624806819, 125516.0, 11280.492377842853, 11489.597864074718, 11514.539418423337, 11537.807816514834, 11737.471813768401, 14359.569212923629, 14387.856121438861, 14416.351264193136, 14416.783748243784, 14432.278386080863, 8166.836663266701, 8428.747137585882, 8452.704155354657, 8491.532965686947, 8574.657013719507, 8296.502209867427, 8368.623411946477, 8372.618517944593, 8487.830243395221, 8555.187257210555, 4391.209582275157, 4507.143642707875, 4525.524819893863, 4585.679002469795, 4604.424446944132, 1885.227056906689, 1931.7971612980434, 1950.405126436422, 1977.5945931084093, 1994.4914046917943, 844.3835999720238, 897.5108565831638, 905.8314773941673, 974.7465658928588, 1041.5052849896238, 17029.40397086096, 20115.597688046157, 20333.08501117453, 20954.057067432, 20990.370288891052, 882.969096081634, 907.7821322882461, 950.3369104067881, 955.9808803823885, 970.2916870684298, 8785.601678942072, 8963.775905602412, 11113.499892504695, 11187.272827266203, 11214.383208923424, 549762.0, 560939.7812043736, 564985.0, 574570.0, 576563.0, 96635.0, 104530.7524258504, 118892.0, 130357.0, 132139.0, 901.6483571407157, 995.9153471954965, 1023.2070145637042, 1050.2438244463974, 1053.9051485366354, 14325.57023289303, 17156.331147834804, 18218.45344639663, 18653.90673046626, 18782.24871005199, 53460.73269633627, 54575.0, 54829.90340193094, 55235.0, 55509.0, 77018.61490692511, 77284.22715772995, 78502.0, 79096.0, 79168.0, 10706.785864282827, 11288.153388495732, 11913.404329783649, 12119.45462454174, 12126.090543209119, 13174.538891138764, 13402.664933376744, 13458.52895148665, 13540.661483462598, 13822.37799299014, 8662.913370866463, 11371.260868043551, 11417.0866330695, 11730.237534560227, 11780.0, 9241.86137207929, 11120.944395277971, 11402.94298528502, 11472.0, 11634.301941883514, 11206.215564910379, 11400.714982125513, 11851.881481185077, 11884.989775869148, 11936.164468487123, 12028.033245957231, 12091.959736547782, 12244.265344079384, 12345.197562158432, 12351.444185011516, 11054.599178441784, 11682.299062056303, 11732.237404568676, 11785.823212651989, 11830.704232394259], "value": 9598.462213513736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.4279645133079, 1552.0, 1800.0999500249875, 1814.2380200315865, 1932.3140285198763, 8034.638441270142, 8287.378446616513, 8504.362172837038, 9117.726468205965, 9134.0, 8536.231739143475, 8821.161989610993, 8856.601452934618, 8928.955355223232, 8929.55352232389, 2326.360250930995, 2355.6808187415018, 2356.5522550715395, 2365.0, 2368.834181607288, 1982.6629472989625, 2099.706041154232, 2328.1269523928527, 2338.754430784769, 2367.7750613691674, 1982.9206831726713, 2046.5804510075473, 2277.5786479501344, 2317.0615900560215, 2364.6807680963107, 2289.5420915816735, 2327.51122264323, 2334.054707843318, 2341.0518739910362, 2342.601757701195, 1729.910156601333, 2240.6190947538958, 2316.0388438797877, 2322.2800931711054, 2326.8720220387872, 6863.450923926074, 8973.596188171483, 9019.639214431481, 9051.321150913707, 9278.072192780735, 5234.607404444644, 5319.255304257407, 5319.973400132974, 5338.5996050296, 5430.484104010113, 3192.920176995594, 3340.46552551593, 3533.381658209829, 3644.4715516249994, 3667.3948798448223, 1025.3130402630256, 1041.5677493840046, 1048.8846226915034, 1050.6585359758033, 1069.2141276162015, 11686.12354073448, 11725.824112638402, 11858.40707964607, 11870.52517899283, 11888.643340699793, 4574.428196475441, 4621.607163391082, 4767.308740232716, 4796.760161991887, 4804.231322988305, 9872.654457094111, 10009.64966226193, 10053.547590358452, 10056.446895420822, 10065.396076235449, 5295.735213239323, 5298.973505132527, 5376.408595054619, 5385.380681221632, 5390.083685773349, 4409.2724700424715, 4567.2007398705755, 4741.786619602126, 4907.214845624683, 4947.060058588857, 3855.768653880776, 3912.158885839558, 3925.4111883217174, 3987.0829709166474, 4061.3704875744306, 983.8783786483415, 1133.9001168866187, 1191.606769765975, 1249.918755280922, 1267.6323866078733, 793.6349279331457, 816.3917881178442, 899.910008999108, 917.9351951735954, 979.0943377375899, 5802.709864506758, 5826.82519524415, 5863.560232982543, 6047.274327080779, 6107.49995030513, 407.7370096287937, 417.7535254199976, 425.27702905061517, 441.1948194544904, 448.2805097817955, 3894.065424298148, 3954.6440820326306, 4010.839566417369, 4105.4252404663375, 4110.445089912883, 8810.86783698245, 10750.301230419998, 10861.07680847121, 10904.182186336056, 10906.182036347305, 8744.644580090096, 9184.21934135606, 10656.840147397796, 10829.350238985686, 10838.0, 8216.0, 8237.670493180205, 8326.916730832736, 8328.958355208306, 8332.458390204618, 5670.829875103754, 5779.624324418899, 5872.207252020922, 5920.230370051866, 5959.702014899324, 9994.550245239127, 10000.399976001463, 10065.949670251895, 10083.798324033332, 10129.392236465836, 9531.332806703742, 9982.401055936667, 10003.199744020607, 10160.136388406772, 10164.288499804943, 6938.5836849789175, 7166.211716711262, 7319.560826350436, 7481.476296659182, 7648.388128949782, 7624.275693809301, 8203.548804815793, 8402.0, 8515.27620152294, 8524.74425767228, 7463.962680186782, 7560.243975602396, 7575.810604734926, 7662.118856331594, 7687.654055567625, 7374.520656157547, 7381.778546643609, 7392.371648409945, 7526.435517336331, 7550.848983020199, 6922.550034247758, 7006.2643422439905, 7089.61007144612, 7092.574445533284, 7093.6453177341955, 4600.6319494439695, 4687.67186296956, 4896.755162241945, 5117.820876269313, 5147.536721695065, 5009.348784657971, 5028.823991160292, 5145.305383773143, 5234.52889239955, 5255.658382205144], "value": 4455.920940353505, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.8284195166803, 313.01868641807926, 313.12325488631825, 320.0941336019065, 321.41021226050196, 5184.1705327147665, 5370.59720520961, 5687.744051517681, 5716.857078573038, 5772.53819694424, 4367.868963931087, 5122.385313762345, 5578.959467090435, 5587.580931430149, 5635.915461268084, 3491.5810102787573, 4019.35690289553, 4125.66994640428, 4152.626263636272, 4194.916101677964, 3656.926861462769, 4184.539700632943, 4196.727212731163, 4200.411942328076, 4243.830246790125, 4154.771487568182, 4200.054987627775, 4217.102303963214, 4229.577042295761, 4233.597808208231, 3125.812451252932, 4016.6786657067655, 4081.6122468365606, 4181.665466762682, 4216.704830661855, 3463.5497385339745, 3643.580988186366, 3646.2342907989355, 3667.193217492174, 3668.358037343481, 4526.4794548627, 4652.55800698933, 5065.0, 5376.005438993799, 5407.324084489439, 3638.727095467824, 3961.98019009906, 4001.7198796084285, 4015.678745700337, 4841.346418233528, 4720.763961801897, 5444.265024221719, 5808.622439541458, 5808.651480911159, 5835.79574714883, 3641.85432582699, 3710.886733979822, 3903.2193561287854, 3908.7654740715648, 3950.822213000422, 5137.48625137487, 5213.296205012312, 5434.375046869621, 5968.194293770329, 6173.789629392735, 3383.780054296463, 3889.2999260132883, 3928.2929072767174, 3939.586343433898, 3953.2686453006286, 3102.3640153768424, 3928.194720082376, 4030.778307193074, 4075.0, 4075.2460794753065, 4042.3330150524835, 4546.657941665701, 4790.952090479121, 4791.401074865642, 4796.688215265996, 5441.646292990943, 5871.794487193013, 5883.588148829541, 5982.670953097534, 6037.577369584087, 1360.6462319796924, 1372.5588132460875, 1372.8627137286192, 1380.758367285721, 1383.4673650644575, 722.8409749855024, 746.3991486853135, 785.0, 846.611557046432, 855.2388374346837, 4411.823527058946, 5185.440417871396, 5655.802046928337, 5676.545876329884, 5688.317401911714, 5782.508486778587, 6218.284897236786, 6233.0338797486465, 6233.688315584202, 6239.097075391958, 4699.483056863743, 5329.360476742816, 5585.609007369527, 5659.094544872801, 5676.687782171937, 3199.8720051197693, 3235.498497732855, 3236.4012657657936, 3260.0382887048377, 3268.0, 5516.448355164452, 5944.227250457413, 6353.047042943595, 6413.146149963315, 6455.61266324022, 5633.295838020247, 6056.878862422727, 6214.564980451415, 6268.655223962634, 6283.246010478681, 5662.7734890603915, 5704.942950570441, 5784.537237021028, 5882.999890018707, 6431.646259455775, 4838.879028024327, 4987.830293822199, 5777.16231146473, 6110.236220472441, 6206.72069756871, 5066.898662026812, 5464.125739881759, 5479.890402191854, 5624.690642014729, 6016.4885984690045, 5350.234422640597, 6430.42782886849, 6470.773522926675, 6479.5788273762055, 6488.643124628191, 3983.143624120828, 4119.475793956136, 4207.347861081537, 4221.409002739681, 4224.1340525191645, 6143.72353244114, 6208.658523781055, 6229.003359462465, 6229.408206220402, 6270.874582508233, 5677.74450149736, 5727.656340619576, 5767.307923049261, 5924.7037648118285, 6057.242844644419, 5947.553933455095, 5959.553033522417, 5972.761089556369, 6010.54920880927, 6012.308584512837, 4476.58987418962, 5065.13892638245, 5699.632088298861, 5887.614621592687, 5905.852353691021, 5431.802861208993, 5464.535514481155, 5656.935218645247, 5906.173135761082, 5969.8806023878415, 5089.862257851637, 5251.501107394792, 5803.303603567599, 5857.179994800646, 5863.618864773776], "value": 4227.583581119123, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [641.0, 733.7982054934893, 794.8291117409759, 796.474326944217, 822.5804839531841, 15351.69296614067, 15660.608484787857, 15883.523494295176, 16279.104649244287, 16303.429379971674, 14742.336594853257, 14810.629734256649, 14886.404543818262, 14887.18120503372, 14892.329845156966, 704.7885634309712, 722.4292808681136, 830.1905641999062, 863.0377129500624, 890.0876601483467, 754.4605606991, 898.9235914947237, 901.4546199549277, 914.1361413464278, 918.0956757593765, 955.7371722776222, 966.5505539923934, 966.6906589891238, 967.7677357434236, 969.2585172343163, 940.8588711693267, 951.6145960885783, 955.6894009446888, 956.9521523923776, 959.5490119643756, 698.1691786773723, 717.5049216040915, 720.8125887269296, 722.3859719238658, 722.8120688620996, 14716.0, 14898.031627944258, 14925.701485970329, 14945.476908308267, 14975.400983960628, 14549.0, 14579.562613121623, 14628.268586570628, 14630.341634626468, 14710.0, 11830.467628956632, 12019.759604807943, 12225.633231002996, 12257.877421225763, 12484.687882802911, 3733.663970242691, 3766.8116594170456, 3916.784576848272, 3957.0898693300414, 3957.703172262092, 115837.42081289539, 116022.0, 116458.0, 117767.0, 124903.75096249011, 9414.576344064533, 11075.837037111149, 11113.499892504857, 11128.276662016942, 11657.475413606408, 22555.661665075037, 23138.884305578365, 23254.209494860388, 23271.7672823273, 23406.0, 7854.057513098464, 7994.68021279154, 8049.557274349967, 8103.270705636521, 8193.50838949665, 4306.569343065668, 4370.322599997005, 4385.561443855589, 4421.403110580095, 4508.549145085531, 3478.913027174341, 3572.7856328620364, 3587.892363229107, 3599.2441587266967, 3606.0804494853646, 649.4998850884792, 650.2489624483729, 665.710415969055, 681.9795406137824, 685.3831551603597, 645.7255666341786, 654.6759354119741, 664.8072059102902, 666.1373521289942, 686.704716971707, 9250.629974801068, 9351.524005249186, 9402.576884040098, 9500.619975201053, 9558.665446709332, 567.6707509644397, 660.7125900233414, 665.950053745971, 682.6006586847137, 691.1464341538197, 4323.1785960668, 4433.866983990485, 4678.836240731558, 4745.6440766942615, 4782.426108866958, 106920.46539767251, 115978.0, 162926.0, 162934.18532907258, 165382.0, 108755.91244087616, 108937.45531272293, 111159.0, 122938.0, 136991.63008369989, 73534.26465735275, 74406.76779696617, 89991.0, 99402.5029874846, 114960.0, 9052.3210759192, 9697.709068727949, 9910.801783964425, 9966.651167209113, 10007.899921000988, 62509.0, 62571.687141564, 63740.777666999005, 64795.67602161959, 65525.37898484198, 60670.0, 62536.0, 62782.372176277655, 84862.0, 86096.52125676493, 13315.66710832237, 13327.733445331232, 13381.459459729658, 13704.588862334145, 13762.449502020208, 13697.452101915811, 13897.444102235797, 14535.636609084857, 14636.634084147981, 14800.333984970486, 11864.525418983145, 13576.0, 13604.727905442034, 14384.06503577264, 14413.9279303607, 13532.93233533826, 13730.794038089236, 13749.106308089942, 13888.08925859377, 13904.791428128789, 13228.07403481747, 13620.523281685093, 13659.521916732867, 13709.862901370858, 13774.242416666779, 9112.0, 11106.0, 11381.0, 11398.715032124263, 11585.304881707125, 8359.414840961317, 8719.564021799011, 10801.513931872927, 11049.83425248638, 11447.656570302905], "value": 7824.826601889601, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1622.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 154.0, 173.0, 95.0, 164.0, 174.0, 96.0, 177.0, 177.0, 170.0], "value": 153.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 117.0, 157.0, 168.0, 116.0, 116.0, 117.0, 119.0, 162.0, 148.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [224.0, 199.0, 200.0, 195.0, 242.0, 259.0, 200.0, 201.0, 201.0, 193.0], "value": 211.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 85.0, 51.0, 85.0, 76.0, 53.0, 50.0, 59.0, 107.0, 85.0], "value": 72.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 103.0, 121.0, 103.0, 74.0, 70.0, 87.0, 71.0, 69.0, 90.0], "value": 89.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 106.0, 107.0, 90.0, 87.0, 90.0, 88.0, 89.0, 87.0], "value": 92.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 90.0, 147.0, 158.0, 153.0, 119.0, 96.0, 96.0, 107.0, 153.0], "value": 127.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 90.0, 86.0, 98.0, 91.0, 86.0, 89.0, 125.0, 76.0, 139.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 58.0, 57.0, 62.0, 51.0, 56.0, 58.0, 58.0], "value": 56.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 93.0, 77.0, 78.0, 75.0, 70.0, 70.0, 70.0, 75.0, 72.0], "value": 75.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 99.0, 109.0, 109.0, 86.0, 91.0, 87.0, 89.0, 91.0, 93.0], "value": 94.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 166.0, 148.0, 150.0, 158.0, 161.0, 167.0, 165.0, 226.0, 143.0], "value": 164.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 177.0, 171.0, 178.0, 173.0, 172.0, 171.0, 179.0, 175.0, 173.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 71.0, 69.0, 72.0, 94.0, 90.0, 87.0, 72.0, 69.0], "value": 76.6, "unit": "score"}]}]} 16:38:12 INFO - Return code: 0 16:38:12 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 16:38:12 ERROR - # TBPL SUCCESS # 16:38:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 16:38:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 16:38:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 16:38:12 INFO - 2016-10-22 16:38:12.276 screenresolution[1676:11030] starting screenresolution argv=screenresolution get 16:38:12 INFO - 2016-10-22 16:38:12.278 screenresolution[1676:11030] Display 0: 1600x1200x32@60 16:38:12 INFO - 2016-10-22 16:38:12.284 screenresolution[1677:11033] starting screenresolution argv=screenresolution list 16:38:12 INFO - Available Modes on Display 0 16:38:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 16:38:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 16:38:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 16:38:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 16:38:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 16:38:12 INFO - Intel Iris: 16:38:12 INFO - Chipset Model: Intel Iris 16:38:12 INFO - Type: GPU 16:38:12 INFO - Bus: Built-In 16:38:12 INFO - VRAM (Dynamic, Max): 1536 MB 16:38:12 INFO - Vendor: Intel (0x8086) 16:38:12 INFO - Device ID: 0x0a2e 16:38:12 INFO - Revision ID: 0x0009 16:38:12 INFO - Displays: 16:38:12 INFO - Display: 16:38:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 16:38:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 16:38:12 INFO - Display Serial Number: 1600x1200 60 16:38:12 INFO - Main Display: Yes 16:38:12 INFO - Mirror: Off 16:38:12 INFO - Online: Yes 16:38:12 INFO - Rotation: Supported 16:38:12 INFO - Return code: 0 16:38:12 INFO - Running post-action listener: _resource_record_post_action 16:38:12 INFO - [mozharness: 2016-10-22 23:38:12.521359Z] Finished run-tests step (success) 16:38:12 INFO - Running post-run listener: _resource_record_post_run 16:38:12 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 33030656; Write bytes: 709625344; Read time: 202; Write time: 1968 16:38:12 INFO - TinderboxPrint: CPU usage
54.6% 16:38:12 INFO - TinderboxPrint: I/O read bytes / time
33,030,656 / 202 16:38:12 INFO - TinderboxPrint: I/O write bytes / time
709,625,344 / 1,968 16:38:12 INFO - TinderboxPrint: CPU idle
2,203.0 (45.4%) 16:38:12 INFO - TinderboxPrint: CPU system
983.0 (20.3%) 16:38:12 INFO - TinderboxPrint: CPU user
1,663.8 (34.3%) 16:38:12 INFO - TinderboxPrint: Swap in / out
433,922,048 / 0 16:38:12 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 402186752; Write bytes: 396195840; Read time: 31009; Write time: 793 16:38:12 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 30962176; Write bytes: 301149696; Read time: 183; Write time: 1135 16:38:13 INFO - Running post-run listener: _upload_blobber_files 16:38:13 INFO - Blob upload gear active. 16:38:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 16:38:13 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 16:38:13 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 16:38:13 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 16:38:13 INFO - (blobuploader) - INFO - Open directory for files ... 16:38:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 16:38:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:14 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 16:38:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:14 INFO - (blobuploader) - INFO - Done attempting. 16:38:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 16:38:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 16:38:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:17 INFO - (blobuploader) - INFO - Done attempting. 16:38:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 16:38:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 16:38:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 16:38:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 16:38:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 16:38:19 INFO - (blobuploader) - INFO - Done attempting. 16:38:19 INFO - (blobuploader) - INFO - Iteration through files over. 16:38:19 INFO - Return code: 0 16:38:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 16:38:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 16:38:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0eebd345d2ef642e9189b4a0abe94198b24a4d7e898b6ff14477a65901183478e4d6060bef2ad0b08896dd8127061c65264909b2abffc43c1fb4b85660b712e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9f2a4b0d9b4a17515347bef5d75b1c9ee0aaada8dcc5453dc9bac963422455c990f44253ad1d5c48ef995d771f64298c32a4a851da104ddf5d2af50513bfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60cac45d8b9270791766af4cdc87d5c7442fcd1a60209a3852420907220457c99ae143aa9af2c9b316e0442606a01668de93e89867bdff7bf532bb3b44aef09"} 16:38:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 16:38:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 16:38:19 INFO - Contents: 16:38:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0eebd345d2ef642e9189b4a0abe94198b24a4d7e898b6ff14477a65901183478e4d6060bef2ad0b08896dd8127061c65264909b2abffc43c1fb4b85660b712e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9f2a4b0d9b4a17515347bef5d75b1c9ee0aaada8dcc5453dc9bac963422455c990f44253ad1d5c48ef995d771f64298c32a4a851da104ddf5d2af50513bfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60cac45d8b9270791766af4cdc87d5c7442fcd1a60209a3852420907220457c99ae143aa9af2c9b316e0442606a01668de93e89867bdff7bf532bb3b44aef09"} 16:38:19 INFO - Running post-run listener: copy_logs_to_upload_dir 16:38:19 INFO - Copying logs to upload dir... 16:38:19 INFO - mkdir: /builds/slave/test/build/upload/logs 16:38:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.645821 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-10-22 16:38:19.502079) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-22 16:38:19.506542) ========= 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.NMBPRSBhxD/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.9FPM6E79ON/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0eebd345d2ef642e9189b4a0abe94198b24a4d7e898b6ff14477a65901183478e4d6060bef2ad0b08896dd8127061c65264909b2abffc43c1fb4b85660b712e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9f2a4b0d9b4a17515347bef5d75b1c9ee0aaada8dcc5453dc9bac963422455c990f44253ad1d5c48ef995d771f64298c32a4a851da104ddf5d2af50513bfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60cac45d8b9270791766af4cdc87d5c7442fcd1a60209a3852420907220457c99ae143aa9af2c9b316e0442606a01668de93e89867bdff7bf532bb3b44aef09"} build_url:https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010482 build_url: 'https://queue.taskcluster.net/v1/task/PILeOEvuTCSKPlSHIrcUPQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b0eebd345d2ef642e9189b4a0abe94198b24a4d7e898b6ff14477a65901183478e4d6060bef2ad0b08896dd8127061c65264909b2abffc43c1fb4b85660b712e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ca9f2a4b0d9b4a17515347bef5d75b1c9ee0aaada8dcc5453dc9bac963422455c990f44253ad1d5c48ef995d771f64298c32a4a851da104ddf5d2af50513bfd1", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d60cac45d8b9270791766af4cdc87d5c7442fcd1a60209a3852420907220457c99ae143aa9af2c9b316e0442606a01668de93e89867bdff7bf532bb3b44aef09"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-22 16:38:19.542652) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-22 16:38:19.546005) ========= 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.NMBPRSBhxD/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.9FPM6E79ON/Listeners TMPDIR=/var/folders/92/0ntvqzfn73q7gppwvgdzq_h400000w/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.005149 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-22 16:38:19.598366) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-22 16:38:19.598690) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-22 16:38:19.599013) ========= ========= Total master_lag: 0.24 =========