builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0320 starttime: 1476446651.77 results: success (0) buildid: 20161014025735 builduid: 66333b3435d245078e8d5b08ae53cde5 revision: 3a35f6cea288558cd0433f1b8b00c02e8bc3e4da ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.770966) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.771405) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.771706) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.795062) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.795397) ========= 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.gy1tiX0DLV/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.1DoAOLezI2/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-14 05:04:12-- 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-14 05:04:12 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127492 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.945611) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.945944) ========= 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.gy1tiX0DLV/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.1DoAOLezI2/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.020965 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:11.994144) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 05:04:11.994504) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3a35f6cea288558cd0433f1b8b00c02e8bc3e4da --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 3a35f6cea288558cd0433f1b8b00c02e8bc3e4da --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.gy1tiX0DLV/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.1DoAOLezI2/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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-14 05:04:12,282 truncating revision to first 12 chars 2016-10-14 05:04:12,282 Setting DEBUG logging. 2016-10-14 05:04:12,282 attempt 1/10 2016-10-14 05:04:12,282 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3a35f6cea288?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-14 05:04:13,767 unpacking tar archive at: mozilla-central-3a35f6cea288/testing/mozharness/ program finished with exit code 0 elapsedTime=1.746701 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-14 05:04:13.765743) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:13.766060) ========= script_repo_revision: 3a35f6cea288558cd0433f1b8b00c02e8bc3e4da ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:13.769487) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:13.769762) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-14 05:04:13.787777) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-10-14 05:04:13.788142) ========= /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.gy1tiX0DLV/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.1DoAOLezI2/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04:14 INFO - MultiFileLogger online at 20161014 05:04:14 in /builds/slave/test 05:04:14 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 05:04:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:04:14 INFO - {'append_to_log': False, 05:04:14 INFO - 'base_work_dir': '/builds/slave/test', 05:04:14 INFO - 'blob_upload_branch': 'Firefox', 05:04:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:04:14 INFO - 'branch': 'Firefox', 05:04:14 INFO - 'buildbot_json_path': 'buildprops.json', 05:04:14 INFO - 'config_files': ('talos/mac_config.py',), 05:04:14 INFO - 'default_actions': ('clobber', 05:04:14 INFO - 'read-buildbot-config', 05:04:14 INFO - 'download-and-extract', 05:04:14 INFO - 'populate-webroot', 05:04:14 INFO - 'create-virtualenv', 05:04:14 INFO - 'install', 05:04:14 INFO - 'run-tests'), 05:04:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:04:14 INFO - 'download_minidump_stackwalk': True, 05:04:14 INFO - 'download_symbols': 'ondemand', 05:04:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:04:14 INFO - 'tooltool.py': '/tools/tooltool.py', 05:04:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:04:14 INFO - '/tools/misc-python/virtualenv.py')}, 05:04:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:04:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:04:14 INFO - 'installer_path': 'installer.exe', 05:04:14 INFO - 'log_level': 'info', 05:04:14 INFO - 'log_name': 'talos', 05:04:14 INFO - 'log_to_console': True, 05:04:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:04:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:04:14 INFO - 'opt_config_files': (), 05:04:14 INFO - 'pip_index': False, 05:04:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:14 INFO - 'cmd': ('bash', 05:04:14 INFO - '-c', 05:04:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:14 INFO - 'enabled': True, 05:04:14 INFO - 'halt_on_failure': False, 05:04:14 INFO - 'name': 'check_screen_resolution'},), 05:04:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:04:14 INFO - 'cmd': ('bash', 05:04:14 INFO - '-c', 05:04:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:04:14 INFO - 'enabled': True, 05:04:14 INFO - 'halt_on_failure': False, 05:04:14 INFO - 'name': 'check_screen_resolution'},), 05:04:14 INFO - 'run_cmd_checks_enabled': True, 05:04:14 INFO - 'sps_profile': False, 05:04:14 INFO - 'sps_profile_interval': 0, 05:04:14 INFO - 'suite': 'dromaeojs-e10s', 05:04:14 INFO - 'system_bits': '32', 05:04:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:04:14 INFO - 'title': 't-yosemite-r7-0320', 05:04:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:04:14 INFO - 'use_talos_json': True, 05:04:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:04:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:04:14 INFO - 'work_dir': 'build'} 05:04:14 INFO - [mozharness: 2016-10-14 12:04:14.192773Z] Running clobber step. 05:04:14 INFO - Running pre-action listener: _resource_record_pre_action 05:04:14 INFO - Running main action method: clobber 05:04:14 INFO - rmtree: /builds/slave/test/build 05:04:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:04:15 INFO - Running post-action listener: _resource_record_post_action 05:04:15 INFO - [mozharness: 2016-10-14 12:04:15.693836Z] Finished clobber step (success) 05:04:15 INFO - [mozharness: 2016-10-14 12:04:15.694116Z] Running read-buildbot-config step. 05:04:15 INFO - Running pre-action listener: _resource_record_pre_action 05:04:15 INFO - Running main action method: read_buildbot_config 05:04:15 INFO - Using buildbot properties: 05:04:15 INFO - { 05:04:15 INFO - "project": "", 05:04:15 INFO - "product": "firefox", 05:04:15 INFO - "script_repo_revision": "production", 05:04:15 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 05:04:15 INFO - "repository": "", 05:04:15 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 05:04:15 INFO - "buildid": "20161014025735", 05:04:15 INFO - "pgo_build": "False", 05:04:15 INFO - "basedir": "/builds/slave/test", 05:04:15 INFO - "buildnumber": 141, 05:04:15 INFO - "slavename": "t-yosemite-r7-0320", 05:04:15 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 05:04:15 INFO - "platform": "yosemite_r7", 05:04:15 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 05:04:15 INFO - "branch": "mozilla-central", 05:04:15 INFO - "repo_path": "mozilla-central", 05:04:15 INFO - "revision": "3a35f6cea288558cd0433f1b8b00c02e8bc3e4da", 05:04:15 INFO - "stage_platform": "macosx64", 05:04:15 INFO - "builduid": "66333b3435d245078e8d5b08ae53cde5", 05:04:15 INFO - "slavebuilddir": "test" 05:04:15 INFO - } 05:04:15 INFO - Found installer url https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:04:15 INFO - Running post-action listener: _resource_record_post_action 05:04:15 INFO - [mozharness: 2016-10-14 12:04:15.699773Z] Finished read-buildbot-config step (success) 05:04:15 INFO - [mozharness: 2016-10-14 12:04:15.699981Z] Running download-and-extract step. 05:04:15 INFO - Running pre-action listener: _resource_record_pre_action 05:04:15 INFO - Running main action method: download_and_extract 05:04:15 INFO - mkdir: /builds/slave/test/build/tests 05:04:15 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')]} 05:04:15 INFO - trying https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:04:15 INFO - Downloading https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/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 05:04:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/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 05:04:16 INFO - Downloaded 1199 bytes. 05:04:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:04:16 INFO - Using the following test package requirements: 05:04:16 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:04:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:04:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:04:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'jsshell-mac.zip'], 05:04:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:04:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:04:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:04:16 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:04:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:04:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:04:16 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:04:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:04:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:04:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:16 INFO - Fetch https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:04:17 INFO - Http code: 200 05:04:17 INFO - via: 1.1 bb8a7ff26b131d51953dc2d3e19a2751.cloudfront.net (CloudFront) 05:04:17 INFO - x-amz-cf-id: dDOwC0APKJzByfz4ophEJ-rRUBa8VdmDjNNeHbKZmpEHsro2ptkr-A== 05:04:17 INFO - x-amz-version-id: BCK5491gHiuTlEU1XrrnLc9qbGTLCPr5 05:04:17 INFO - Expected file size: 16515321 05:04:17 INFO - Obtained file size: 16515321 05:04:24 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 05:04:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:04:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:04:24 INFO - Fetch https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:04:24 INFO - Http code: 200 05:04:24 INFO - via: 1.1 4c2196e36fd33f377384d605b4424e95.cloudfront.net (CloudFront) 05:04:24 INFO - x-amz-cf-id: P3_gbkywlLzeAdOq5RVDtIsvVIubBClKZ9zTot3bIvOuhsAmtbXIjQ== 05:04:24 INFO - x-amz-version-id: BCK5491gHiuTlEU1XrrnLc9qbGTLCPr5 05:04:25 INFO - Expected file size: 16515321 05:04:25 INFO - Obtained file size: 16515321 05:04:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:04:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:04:31 INFO - Fetch https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:04:32 INFO - Http code: 200 05:04:32 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 05:04:32 INFO - x-amz-cf-id: z67-bu4DbXJO9DoxhWoL_8LIgBVJ-iY732S3t_0TWz2l-I7KB_F08A== 05:04:32 INFO - x-amz-version-id: ehCMyLOnH39eoS8rn6dH6jtAGg_8rDl2 05:04:32 INFO - Expected file size: 14409006 05:04:32 INFO - Obtained file size: 14409006 05:04:32 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')]} 05:04:32 INFO - trying https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:04:32 INFO - Downloading https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:04:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/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 05:04:35 INFO - Downloaded 111478420 bytes. 05:04:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:04:35 INFO - mkdir: /builds/slave/test/properties 05:04:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:04:35 INFO - Writing to file /builds/slave/test/properties/build_url 05:04:35 INFO - Contents: 05:04:35 INFO - build_url:https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:04:36 INFO - Running post-action listener: _resource_record_post_action 05:04:36 INFO - Running post-action listener: set_extra_try_arguments 05:04:36 INFO - [mozharness: 2016-10-14 12:04:36.410461Z] Finished download-and-extract step (success) 05:04:36 INFO - [mozharness: 2016-10-14 12:04:36.410590Z] Running populate-webroot step. 05:04:36 INFO - Running pre-action listener: _resource_record_pre_action 05:04:36 INFO - Running main action method: populate_webroot 05:04:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:04:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:04:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:04:36 INFO - '--tppagecycles', 05:04:36 INFO - '7'], 05:04:36 INFO - 'tests': ['tsvgm']}}, 05:04:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:04:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:04:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:04:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:04:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:04:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['damp', 'tps']}, 05:04:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'tests': ['damp', 'tps']}, 05:04:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['dromaeo_dom']}, 05:04:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:04:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['basic_compositor_video']}, 05:04:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:04:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['a11yr', 05:04:36 INFO - 'ts_paint', 05:04:36 INFO - 'tpaint', 05:04:36 INFO - 'sessionrestore', 05:04:36 INFO - 'sessionrestore_no_auto_restore', 05:04:36 INFO - 'tabpaint']}, 05:04:36 INFO - 'other-e10s': {'tests': ['a11yr', 05:04:36 INFO - 'ts_paint', 05:04:36 INFO - 'tpaint', 05:04:36 INFO - 'sessionrestore', 05:04:36 INFO - 'sessionrestore_no_auto_restore', 05:04:36 INFO - 'tabpaint']}, 05:04:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:04:36 INFO - 'ts_paint', 05:04:36 INFO - 'tpaint', 05:04:36 INFO - 'sessionrestore', 05:04:36 INFO - 'sessionrestore_no_auto_restore', 05:04:36 INFO - 'tabpaint']}, 05:04:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:04:36 INFO - 'ts_paint', 05:04:36 INFO - 'tpaint', 05:04:36 INFO - 'sessionrestore', 05:04:36 INFO - 'sessionrestore_no_auto_restore', 05:04:36 INFO - 'tabpaint']}, 05:04:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['a11yr', 05:04:36 INFO - 'ts_paint', 05:04:36 INFO - 'tpaint', 05:04:36 INFO - 'sessionrestore', 05:04:36 INFO - 'sessionrestore_no_auto_restore', 05:04:36 INFO - 'tabpaint']}, 05:04:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['a11yr', 05:04:36 INFO - 'ts_paint', 05:04:36 INFO - 'tpaint', 05:04:36 INFO - 'sessionrestore', 05:04:36 INFO - 'sessionrestore_no_auto_restore', 05:04:36 INFO - 'tabpaint']}, 05:04:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['tsvgx', 05:04:36 INFO - 'tsvgr_opacity', 05:04:36 INFO - 'tart', 05:04:36 INFO - 'tscrollx', 05:04:36 INFO - 'cart']}, 05:04:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:04:36 INFO - 'tsvgr_opacity', 05:04:36 INFO - 'tart', 05:04:36 INFO - 'tscrollx', 05:04:36 INFO - 'cart']}, 05:04:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'talos_options': ['--disable-e10s'], 05:04:36 INFO - 'tests': ['tp5o']}, 05:04:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'tests': ['tp5o']}, 05:04:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'talos_options': ['--disable-e10s', 05:04:36 INFO - '--xperf_path', 05:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:36 INFO - 'tests': ['tp5n']}, 05:04:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:04:36 INFO - 'talos_options': ['--xperf_path', 05:04:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:04:36 INFO - 'tests': ['tp5n']}}, 05:04:36 INFO - 'talos.zip': {'path': '', 05:04:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:04:36 INFO - Running post-action listener: _resource_record_post_action 05:04:36 INFO - [mozharness: 2016-10-14 12:04:36.428961Z] Finished populate-webroot step (success) 05:04:36 INFO - [mozharness: 2016-10-14 12:04:36.429087Z] Running create-virtualenv step. 05:04:36 INFO - Running pre-action listener: _resource_record_pre_action 05:04:36 INFO - Running main action method: create_virtualenv 05:04:36 INFO - Creating virtualenv /builds/slave/test/build/venv 05:04:36 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 05:04:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:04:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:04:36 INFO - Using real prefix '/tools/python27' 05:04:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:04:37 INFO - Installing distribute.............................................................................................................................................................................................done. 05:04:40 INFO - Installing pip.................done. 05:04:40 INFO - Return code: 0 05:04:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:04:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:04:40 INFO - Reading from file tmpfile_stdout 05:04:40 INFO - Output received: 05:04:40 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) 05:04:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:04: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')]} 05:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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')]} 05:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04: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', 'pip>=1.5'] in /builds/slave/test/build 05:04: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 pip>=1.5 05:04:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:40 INFO - 'HOME': '/Users/cltbld', 05:04:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:40 INFO - 'LOGNAME': 'cltbld', 05:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:40 INFO - 'MOZ_NO_REMOTE': '1', 05:04:40 INFO - 'NO_EM_RESTART': '1', 05:04:40 INFO - 'PAGER': '/bin/cat', 05:04:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:40 INFO - 'PWD': '/builds/slave/test', 05:04:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:40 INFO - 'SHELL': '/bin/bash', 05:04:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:40 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:40 INFO - 'USER': 'cltbld', 05:04:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:40 INFO - 'XPC_FLAGS': '0x0', 05:04:40 INFO - 'XPC_SERVICE_NAME': '0', 05:04:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:40 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 05:04:40 INFO - Cleaning up... 05:04:40 INFO - Return code: 0 05:04:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:04: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')]} 05:04:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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')]} 05:04:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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', '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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04: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', 'psutil>=3.1.1'] in /builds/slave/test/build 05:04: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 psutil>=3.1.1 05:04:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:40 INFO - 'HOME': '/Users/cltbld', 05:04:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:40 INFO - 'LOGNAME': 'cltbld', 05:04:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:40 INFO - 'MOZ_NO_REMOTE': '1', 05:04:40 INFO - 'NO_EM_RESTART': '1', 05:04:40 INFO - 'PAGER': '/bin/cat', 05:04:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:40 INFO - 'PWD': '/builds/slave/test', 05:04:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:40 INFO - 'SHELL': '/bin/bash', 05:04:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:40 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:40 INFO - 'USER': 'cltbld', 05:04:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:40 INFO - 'XPC_FLAGS': '0x0', 05:04:40 INFO - 'XPC_SERVICE_NAME': '0', 05:04:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:40 INFO - Downloading/unpacking psutil>=3.1.1 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:04:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:44 INFO - Installing collected packages: psutil 05:04:44 INFO - Running setup.py install for psutil 05:04:44 INFO - building 'psutil._psutil_osx' extension 05:04:44 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 05:04:44 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 05:04:44 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 05:04:44 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 05:04:44 INFO - building 'psutil._psutil_posix' extension 05:04:44 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 05:04:44 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:04:44 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:04:44 INFO - ^ 05:04:45 INFO - 1 warning generated. 05:04:45 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 05:04:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:04:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:04:45 INFO - Successfully installed psutil 05:04:45 INFO - Cleaning up... 05:04:45 INFO - Return code: 0 05:04:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:04:45 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')]} 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 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')]} 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:45 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04:45 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 05:04:45 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 05:04:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:45 INFO - 'HOME': '/Users/cltbld', 05:04:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:45 INFO - 'LOGNAME': 'cltbld', 05:04:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:45 INFO - 'MOZ_NO_REMOTE': '1', 05:04:45 INFO - 'NO_EM_RESTART': '1', 05:04:45 INFO - 'PAGER': '/bin/cat', 05:04:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:45 INFO - 'PWD': '/builds/slave/test', 05:04:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:45 INFO - 'SHELL': '/bin/bash', 05:04:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:45 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:45 INFO - 'USER': 'cltbld', 05:04:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:45 INFO - 'XPC_FLAGS': '0x0', 05:04:45 INFO - 'XPC_SERVICE_NAME': '0', 05:04:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:04:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:04:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:04:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:04:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:04:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:04:48 INFO - Installing collected packages: mozsystemmonitor 05:04:48 INFO - Running setup.py install for mozsystemmonitor 05:04:48 INFO - Successfully installed mozsystemmonitor 05:04:48 INFO - Cleaning up... 05:04:48 INFO - Return code: 0 05:04:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:04: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')]} 05:04:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04: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')]} 05:04:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:48 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04:48 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 05:04:48 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 05:04:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:48 INFO - 'HOME': '/Users/cltbld', 05:04:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:48 INFO - 'LOGNAME': 'cltbld', 05:04:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:48 INFO - 'MOZ_NO_REMOTE': '1', 05:04:48 INFO - 'NO_EM_RESTART': '1', 05:04:48 INFO - 'PAGER': '/bin/cat', 05:04:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:48 INFO - 'PWD': '/builds/slave/test', 05:04:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:48 INFO - 'SHELL': '/bin/bash', 05:04:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:48 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:48 INFO - 'USER': 'cltbld', 05:04:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:48 INFO - 'XPC_FLAGS': '0x0', 05:04:48 INFO - 'XPC_SERVICE_NAME': '0', 05:04:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:48 INFO - Downloading/unpacking jsonschema==2.5.1 05:04:48 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 05:04:48 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 05:04:48 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 05:04:48 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 05:04:48 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 05:04:48 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 05:04:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:04:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:04:51 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 05:04:51 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 05:04:51 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 05:04:51 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 05:04:51 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 05:04:51 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 05:04:51 INFO - Downloading functools32-3.2.3-2.tar.gz 05:04:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:04:51 INFO - warning: no files found matching '*.txt' 05:04:51 INFO - no previously-included directories found matching 'build' 05:04:51 INFO - no previously-included directories found matching 'dist' 05:04:51 INFO - no previously-included directories found matching '.git*' 05:04:51 INFO - Installing collected packages: jsonschema, functools32 05:04:51 INFO - Running setup.py install for functools32 05:04:51 INFO - warning: no files found matching '*.txt' 05:04:51 INFO - no previously-included directories found matching 'build' 05:04:51 INFO - no previously-included directories found matching 'dist' 05:04:51 INFO - no previously-included directories found matching '.git*' 05:04:51 INFO - Successfully installed jsonschema functools32 05:04:51 INFO - Cleaning up... 05:04:51 INFO - Return code: 0 05:04:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:51 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04:51 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 05:04:51 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 05:04:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:51 INFO - 'HOME': '/Users/cltbld', 05:04:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:51 INFO - 'LOGNAME': 'cltbld', 05:04:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:51 INFO - 'MOZ_NO_REMOTE': '1', 05:04:51 INFO - 'NO_EM_RESTART': '1', 05:04:51 INFO - 'PAGER': '/bin/cat', 05:04:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:51 INFO - 'PWD': '/builds/slave/test', 05:04:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:51 INFO - 'SHELL': '/bin/bash', 05:04:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:51 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:51 INFO - 'USER': 'cltbld', 05:04:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:51 INFO - 'XPC_FLAGS': '0x0', 05:04:51 INFO - 'XPC_SERVICE_NAME': '0', 05:04:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:04:52 INFO - Cleaning up... 05:04:52 INFO - Return code: 0 05:04:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:52 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04:52 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 05:04:52 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 05:04:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:52 INFO - 'HOME': '/Users/cltbld', 05:04:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:52 INFO - 'LOGNAME': 'cltbld', 05:04:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:52 INFO - 'MOZ_NO_REMOTE': '1', 05:04:52 INFO - 'NO_EM_RESTART': '1', 05:04:52 INFO - 'PAGER': '/bin/cat', 05:04:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:52 INFO - 'PWD': '/builds/slave/test', 05:04:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:52 INFO - 'SHELL': '/bin/bash', 05:04:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:52 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:52 INFO - 'USER': 'cltbld', 05:04:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:52 INFO - 'XPC_FLAGS': '0x0', 05:04:52 INFO - 'XPC_SERVICE_NAME': '0', 05:04:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:52 INFO - Downloading/unpacking blobuploader==1.2.4 05:04:52 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 05:04:52 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 05:04:52 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 05:04:52 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 05:04:52 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 05:04:52 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 05:04:54 INFO - Downloading blobuploader-1.2.4.tar.gz 05:04:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:04:54 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:04:54 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 05:04:54 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 05:04:54 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 05:04:54 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 05:04:54 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 05:04:54 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 05:04:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:04:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04: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 05:04:56 INFO - Downloading docopt-0.6.1.tar.gz 05:04:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:04:56 INFO - Installing collected packages: blobuploader, requests, docopt 05:04:56 INFO - Running setup.py install for blobuploader 05:04:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:04:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:04:56 INFO - Running setup.py install for requests 05:04:56 INFO - Running setup.py install for docopt 05:04:56 INFO - Successfully installed blobuploader requests docopt 05:04:56 INFO - Cleaning up... 05:04:57 INFO - Return code: 0 05:04:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:04:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:04:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:04:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:04:57 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:04:57 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 05:04:57 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 05:04:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:04:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:04:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:04:57 INFO - 'HOME': '/Users/cltbld', 05:04:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:04:57 INFO - 'LOGNAME': 'cltbld', 05:04:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:04:57 INFO - 'MOZ_NO_REMOTE': '1', 05:04:57 INFO - 'NO_EM_RESTART': '1', 05:04:57 INFO - 'PAGER': '/bin/cat', 05:04:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:04:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:04:57 INFO - 'PWD': '/builds/slave/test', 05:04:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:04:57 INFO - 'SHELL': '/bin/bash', 05:04:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:04:57 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:04:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:04:57 INFO - 'USER': 'cltbld', 05:04:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:04:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:04:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:04:57 INFO - 'XPC_FLAGS': '0x0', 05:04:57 INFO - 'XPC_SERVICE_NAME': '0', 05:04:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:04:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-x3J8Fv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-CnmAnN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-cBc_5U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-c8BlyT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-e73j3P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-YxL9ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:04:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:04:57 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-p2aXtT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-q_2aNe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-abCnhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-eNJuYP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-xJmVED-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-tEmcgU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-FeoUhm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-KNBk10-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-7KomyM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-_limT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:04:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:04:58 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-Seg9TU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:04:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:04:59 INFO - Running setup.py install for manifestparser 05:04:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:04:59 INFO - Running setup.py install for mozcrash 05:04:59 INFO - Running setup.py install for mozdebug 05:04:59 INFO - Running setup.py install for mozdevice 05:04:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:04:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:04:59 INFO - Running setup.py install for mozfile 05:04:59 INFO - Running setup.py install for mozhttpd 05:04:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:04:59 INFO - Running setup.py install for mozinfo 05:05:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Running setup.py install for mozInstall 05:05:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Running setup.py install for mozleak 05:05:00 INFO - Running setup.py install for mozlog 05:05:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Running setup.py install for moznetwork 05:05:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:00 INFO - Running setup.py install for mozprocess 05:05:00 INFO - Running setup.py install for mozprofile 05:05:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:01 INFO - Running setup.py install for mozrunner 05:05:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:01 INFO - Running setup.py install for mozscreenshot 05:05:01 INFO - Running setup.py install for moztest 05:05:01 INFO - Running setup.py install for mozversion 05:05:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:01 INFO - Cleaning up... 05:05:01 INFO - Return code: 0 05:05:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:01 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:05:01 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 05:05:01 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 05:05:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:05:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:01 INFO - 'HOME': '/Users/cltbld', 05:05:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:01 INFO - 'LOGNAME': 'cltbld', 05:05:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:01 INFO - 'MOZ_NO_REMOTE': '1', 05:05:01 INFO - 'NO_EM_RESTART': '1', 05:05:01 INFO - 'PAGER': '/bin/cat', 05:05:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:01 INFO - 'PWD': '/builds/slave/test', 05:05:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:01 INFO - 'SHELL': '/bin/bash', 05:05:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:05:01 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:05:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:01 INFO - 'USER': 'cltbld', 05:05:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:01 INFO - 'XPC_FLAGS': '0x0', 05:05:01 INFO - 'XPC_SERVICE_NAME': '0', 05:05:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:01 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-kbwbwZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-11qTwx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-PwVRnx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-mlNxNf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-_eQirA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-OSFSfA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-aAfkeZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-zSKJxm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-IH6tQS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:02 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)) 05:05:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:02 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-j76PsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-CxnVnU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-BI7Zeg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-_DoMhj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-SaWZFN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-IT18sT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-DULcro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:03 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)) 05:05:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:03 INFO - Running setup.py (path:/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/pip-fYH3SL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:03 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)) 05:05:03 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)) 05:05:03 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)) 05:05:03 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)) 05:05:03 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)) 05:05:03 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)) 05:05:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:06 INFO - Downloading blessings-1.6.tar.gz 05:05:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:05:06 INFO - Installing collected packages: blessings 05:05:06 INFO - Running setup.py install for blessings 05:05:06 INFO - Successfully installed blessings 05:05:06 INFO - Cleaning up... 05:05:06 INFO - Return code: 0 05:05:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:05:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:06 INFO - Reading from file tmpfile_stdout 05:05:06 INFO - Current package versions: 05:05:06 INFO - blessings == 1.6 05:05:06 INFO - blobuploader == 1.2.4 05:05:06 INFO - docopt == 0.6.1 05:05:06 INFO - functools32 == 3.2.3-2 05:05:06 INFO - jsonschema == 2.5.1 05:05:06 INFO - manifestparser == 1.1 05:05:06 INFO - mozInstall == 1.12 05:05:06 INFO - mozcrash == 0.17 05:05:06 INFO - mozdebug == 0.1 05:05:06 INFO - mozdevice == 0.48 05:05:06 INFO - mozfile == 1.2 05:05:06 INFO - mozhttpd == 0.7 05:05:06 INFO - mozinfo == 0.9 05:05:06 INFO - mozleak == 0.1 05:05:06 INFO - mozlog == 3.4 05:05:06 INFO - moznetwork == 0.27 05:05:06 INFO - mozprocess == 0.23 05:05:06 INFO - mozprofile == 0.28 05:05:06 INFO - mozrunner == 6.12 05:05:06 INFO - mozscreenshot == 0.1 05:05:06 INFO - mozsystemmonitor == 0.3 05:05:06 INFO - moztest == 0.7 05:05:06 INFO - mozversion == 1.4 05:05:06 INFO - psutil == 3.1.1 05:05:06 INFO - requests == 1.2.3 05:05:06 INFO - wsgiref == 0.1.2 05:05:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:06 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:05:06 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 05:05:06 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 05:05:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:05:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:06 INFO - 'HOME': '/Users/cltbld', 05:05:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:06 INFO - 'LOGNAME': 'cltbld', 05:05:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:06 INFO - 'MOZ_NO_REMOTE': '1', 05:05:06 INFO - 'NO_EM_RESTART': '1', 05:05:06 INFO - 'PAGER': '/bin/cat', 05:05:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:06 INFO - 'PWD': '/builds/slave/test', 05:05:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:06 INFO - 'SHELL': '/bin/bash', 05:05:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:05:06 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:05:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:06 INFO - 'USER': 'cltbld', 05:05:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:06 INFO - 'XPC_FLAGS': '0x0', 05:05:06 INFO - 'XPC_SERVICE_NAME': '0', 05:05:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 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)) 05:05:07 INFO - Cleaning up... 05:05:07 INFO - Return code: 0 05:05:07 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:07 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 0x1073e2490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107358570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffce95a42d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, '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.gy1tiX0DLV/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.1DoAOLezI2/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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 05:05:07 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 05:05:07 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 05:05:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:05:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:07 INFO - 'HOME': '/Users/cltbld', 05:05:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:07 INFO - 'LOGNAME': 'cltbld', 05:05:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:07 INFO - 'MOZ_NO_REMOTE': '1', 05:05:07 INFO - 'NO_EM_RESTART': '1', 05:05:07 INFO - 'PAGER': '/bin/cat', 05:05:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:07 INFO - 'PWD': '/builds/slave/test', 05:05:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:07 INFO - 'SHELL': '/bin/bash', 05:05:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:05:07 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:05:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:07 INFO - 'USER': 'cltbld', 05:05:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:07 INFO - 'XPC_FLAGS': '0x0', 05:05:07 INFO - 'XPC_SERVICE_NAME': '0', 05:05:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:05:07 INFO - Cleaning up... 05:05:07 INFO - Return code: 0 05:05:07 INFO - Running post-action listener: _resource_record_post_action 05:05:07 INFO - Running post-action listener: _start_resource_monitoring 05:05:07 INFO - Starting resource monitoring. 05:05:07 INFO - [mozharness: 2016-10-14 12:05:07.437229Z] Finished create-virtualenv step (success) 05:05:07 INFO - [mozharness: 2016-10-14 12:05:07.438234Z] Running install step. 05:05:07 INFO - Running pre-action listener: _resource_record_pre_action 05:05:07 INFO - Running main action method: install 05:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:05:07 INFO - Reading from file tmpfile_stdout 05:05:07 INFO - Detecting whether we're running mozinstall >=1.0... 05:05:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:05:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:05:07 INFO - Reading from file tmpfile_stdout 05:05:07 INFO - Output received: 05:05:07 INFO - Usage: mozinstall [options] installer 05:05:07 INFO - Options: 05:05:07 INFO - -h, --help show this help message and exit 05:05:07 INFO - -d DEST, --destination=DEST 05:05:07 INFO - Directory to install application into. [default: 05:05:07 INFO - "/builds/slave/test"] 05:05:07 INFO - --app=APP Application being installed. [default: firefox] 05:05:07 INFO - mkdir: /builds/slave/test/build/application 05:05:07 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'] 05:05:07 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 05:05:42 INFO - Reading from file tmpfile_stdout 05:05:42 INFO - Output received: 05:05:42 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:05:42 INFO - Running post-action listener: _resource_record_post_action 05:05:42 INFO - [mozharness: 2016-10-14 12:05:42.442664Z] Finished install step (success) 05:05:42 INFO - [mozharness: 2016-10-14 12:05:42.442825Z] Running run-tests step. 05:05:42 INFO - Running pre-action listener: _resource_record_pre_action 05:05:42 INFO - Running main action method: run_tests 05:05:42 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:05:42 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:05:42 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:05:42 INFO - 2016-10-14 05:05:42.457 screenresolution[1924:15780] starting screenresolution argv=screenresolution get 05:05:42 INFO - 2016-10-14 05:05:42.460 screenresolution[1924:15780] Display 0: 1600x1200x32@60 05:05:42 INFO - 2016-10-14 05:05:42.466 screenresolution[1925:15782] starting screenresolution argv=screenresolution list 05:05:42 INFO - Available Modes on Display 0 05:05:42 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:05:42 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:05:42 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:05:42 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:05:42 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:05:42 INFO - Intel Iris: 05:05:42 INFO - Chipset Model: Intel Iris 05:05:42 INFO - Type: GPU 05:05:42 INFO - Bus: Built-In 05:05:42 INFO - VRAM (Dynamic, Max): 1536 MB 05:05:42 INFO - Vendor: Intel (0x8086) 05:05:42 INFO - Device ID: 0x0a2e 05:05:42 INFO - Revision ID: 0x0009 05:05:42 INFO - Displays: 05:05:42 INFO - Display: 05:05:42 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:05:42 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:05:42 INFO - Display Serial Number: 1600x1200 60 05:05:42 INFO - Main Display: Yes 05:05:42 INFO - Mirror: Off 05:05:42 INFO - Online: Yes 05:05:42 INFO - Rotation: Supported 05:05:42 INFO - Return code: 0 05:05:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:05:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:05:42 INFO - Python 2.7.3 05:05:42 INFO - Return code: 0 05:05:42 INFO - grabbing minidump binary from tooltool 05:05:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:42 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 0x1071f6d30>, '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 0x10701cdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1071eeb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:05:42 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 05:05:42 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 05:05:42 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 05:05:42 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:05:42 INFO - Return code: 0 05:05:42 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:05:42 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:05:42 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:05:42 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:05:42 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:05:42 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/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 05:05:42 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/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0320 --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 05:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.gy1tiX0DLV/Render', 05:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:42 INFO - 'HOME': '/Users/cltbld', 05:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:42 INFO - 'LOGNAME': 'cltbld', 05:05:42 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:05:42 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:42 INFO - 'MOZ_NO_REMOTE': '1', 05:05:42 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:05:42 INFO - 'NO_EM_RESTART': '1', 05:05:42 INFO - 'PAGER': '/bin/cat', 05:05:42 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:42 INFO - 'PWD': '/builds/slave/test', 05:05:42 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:42 INFO - 'SHELL': '/bin/bash', 05:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1DoAOLezI2/Listeners', 05:05:42 INFO - 'TMPDIR': '/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/', 05:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:42 INFO - 'USER': 'cltbld', 05:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:42 INFO - 'XPC_FLAGS': '0x0', 05:05:42 INFO - 'XPC_SERVICE_NAME': '0', 05:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:42 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/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0320', '--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 05:05:43 INFO - mozversion application_buildid: 20161014025735 05:05:43 INFO - mozversion application_changeset: 3a35f6cea288558cd0433f1b8b00c02e8bc3e4da 05:05:43 INFO - mozversion application_display_name: Nightly 05:05:43 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:05:43 INFO - mozversion application_name: Firefox 05:05:43 INFO - mozversion application_remotingname: firefox 05:05:43 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 05:05:43 INFO - mozversion application_vendor: Mozilla 05:05:43 INFO - mozversion application_version: 52.0a1 05:05:43 INFO - mozversion platform_buildid: 20161014025735 05:05:43 INFO - mozversion platform_changeset: 3a35f6cea288558cd0433f1b8b00c02e8bc3e4da 05:05:43 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 05:05:43 INFO - mozversion platform_version: 52.0a1 05:05:43 INFO - using testdate: 1476446743 05:05:43 INFO - actual date: 1476446743 05:05:43 INFO - starting webserver on 'localhost:52122' 05:05:43 INFO - SUITE-START | Running 2 tests 05:05:43 INFO - TEST-START | dromaeo_css 05:05:43 INFO - operating with platform_type : mac_ 05:05:43 INFO - Initialising browser for dromaeo_css test... 05:05:43 INFO - TEST-INFO | started process 1935 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpP1xTxT/profile http://localhost:52122/getInfo.html) 05:05:45 INFO - PROCESS | 1935 | 2016-10-14 05:05:45.672 plugin-container[1937:15945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 05:05:45 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:45 INFO - PROCESS | 1935 | 2016-10-14 05:05:45.675 plugin-container[1937:15945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.CFPasteboardClient' 05:05:45 INFO - PROCESS | 1935 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:45 INFO - PROCESS | 1935 | 2016-10-14 05:05:45.675 plugin-container[1937:15945] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:05:45 INFO - PROCESS | 1935 | __metrics Screen width/height:1600/1200 05:05:45 INFO - PROCESS | 1935 | colorDepth:24 05:05:45 INFO - PROCESS | 1935 | Browser inner width/height: 1024/689 05:05:45 INFO - PROCESS | 1935 | __metrics 05:05:45 ERROR - PROCESS | 1935 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 05:05:46 INFO - TEST-INFO | 1935: exit 0 05:05:46 INFO - Browser initialized. 05:05:46 INFO - Running cycle 1/1 for dromaeo_css test... 05:05:46 INFO - TEST-INFO | started process 1938 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpP1xTxT/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:05:49 INFO - PROCESS | 1938 | 2016-10-14 05:05:49.050 plugin-container[1939:16068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4343, name = 'com.apple.tsm.portname' 05:05:49 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:49 INFO - PROCESS | 1938 | 2016-10-14 05:05:49.051 plugin-container[1939:16068] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:05:49 INFO - PROCESS | 1938 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:05:49 INFO - PROCESS | 1938 | 2016-10-14 05:05:49.051 plugin-container[1939:16068] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:05:58 INFO - PROCESS | 1938 | RSS: Main: 239280128 05:05:58 INFO - PROCESS | 1938 | 05:06:48 INFO - PROCESS | 1938 | 1476446808303 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] 05:09:02 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52122/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52122/tests/dromaeo/cssquery-ext.html) 05:09:04 INFO - PROCESS | 1938 | RSS: Main: 254468096 05:09:04 INFO - PROCESS | 1938 | 05:12:06 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52122/tests/dromaeo/cssquery-ext.html (next: http://localhost:52122/tests/dromaeo/cssquery-jquery.html) 05:12:06 INFO - PROCESS | 1938 | RSS: Main: 267796480 05:12:06 INFO - PROCESS | 1938 | 05:15:08 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52122/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52122/tests/dromaeo/cssquery-mootools.html) 05:15:09 INFO - PROCESS | 1938 | RSS: Main: 271454208 05:15:09 INFO - PROCESS | 1938 | 05:18:11 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52122/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52122/tests/dromaeo/cssquery-prototype.html) 05:18:11 INFO - PROCESS | 1938 | RSS: Main: 269996032 05:18:11 INFO - PROCESS | 1938 | 05:21:14 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52122/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52122/tests/dromaeo/cssquery-yui.html) 05:21:15 INFO - PROCESS | 1938 | RSS: Main: 275214336 05:21:15 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | Cycle 1(1): loaded http://localhost:52122/tests/dromaeo/cssquery-yui.html (next: http://localhost:52122/tests/dromaeo/cssquery-dojo.html) 05:24:18 INFO - PROCESS | 1938 | RSS: Main: 277950464 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | __start_tp_report 05:24:18 INFO - PROCESS | 1938 | _x_x_mozilla_page_load 05:24:18 INFO - PROCESS | 1938 | _x_x_mozilla_page_load_details 05:24:18 INFO - PROCESS | 1938 | |i|pagename|runs| 05:24:18 INFO - PROCESS | 1938 | |0;dojo.html;3037.7721670874685;3335.2995870867117;3359.2609625882315;3377.408953433149;3390.7456940729453;5632.7746890124345;5652.830415087549;5758.165066065423;5796.391378905218;5801.6519008859395;5731.942680573204;5767.10609855472;5786.189933409316;5855.443732845383;5888.941110588893;5188.792448302063;5204.76578553966;5207.765650545734;5340.54605358545;5368.892622147574;5303.337082864642;5355.277037599933;5356.276902618136;5358.2766326546;5441.646292990943;5150.922736158961;5194.065068287707;5260.421353651095;5305.310309659758;5309.654872433318;4936.728479933602;5243.580513558944;5271.762770675329;5291.761870715827;5300.3639563252655;5183.974080129613;5211.478852114796;5252.080885844962;5253.579713622939;5273.894522109575;5624.268845050117;5687.4881260686325;5733.827985160452;5838.766449342022;5846.824595262149;6101.277634935909;6115.240341084353;6126.827321259284;6137.631742095444;6151.753929842801;6219.378062193744;6226.159468471744;6230.314665386803;6248.718807653667;6250.718717657716;5815.563832712563;5818.6217895836635;5924.052151655715;5934.1098835174635;5970.402959704082;6235.189425374763;6240.594361366497;6245.0632405139595;6250.531210159256;6267.2792628847355;6143.662098584621;6152.261728592597;6156;6157;6169.321374648785;6097.0105204680185;6123.173371594867;6132.998044146438;6159.630422174684;6212.565120441525;6204.472619827366;6205.937940620626;6221.3156552779155;6223.222097237845;6224.346443623354;6083.330833608388;6173.722182501798;6227.782027629011;6228.813135605939;6239.719212635442;4099.651529620016;4878.902421951541;4904.485029071968;4915.238138088602;4981.352424184833;4396.516383197845;4429.9778501107285;4446.73319600825;4510.120526497301;4547;5866.6480011199465;5901.616394934315;5979.880402392015;5994.190784244071;5998.640081595118;6119.8681629442035;6137.683438940172;6164.044573091178;6217.191765070603;6222.533310001768;5866.911996320058;5926.288845338587;5936.406359364116;5983.820485385445;5991;5426.511613954763;5436.537894278951;5443.183522471661;5445.156000819879;5460.7542660579575;6130.795626845537;6204.224471941007;6273.278572964167;6298.464630506458;6359.777407790705;6141.547378150805;6143.96928015357;6147.84630384244;6224.62652240867;6226.3151053385;6319.146915166578;6322.178116844781;6344.016677415008;6348.238211414659;6433.517486188461;5121.8207362743615;6085.782843431243;6100.914243738686;6111.930412177762;6214.285357183981;6195.31851496326;6207.068939659087;6219.222597175353;6223.657698826608;6224.346443623354;5932.139839723302;6099.176611157437;6134.693265336626;6143.139960405458;6157.907631385622;4516.661250406299;5104;5672.659640421588;6042.788502402394;6081.013867059388;4847.442544107473;5023.924641130459;5965.194698715618;6122.510199184144;6129.387061293835;6103.8779224414375;6142.539309551712;6167.321594624498;6168.290646575521;6179.16581261524;5869.882602348014;5944.673042982505;6049.606775559574;6053.213082299273;6069.514438844793 05:24:18 INFO - PROCESS | 1938 | |1;ext.html;444.53768081195557;475.26096919290535;490.0688691486177;496.06739330059503;497.94771548987353;37765;39194.60805391945;39338.409923851126;41240.38139427911;41312;32087.518687219712;41114.58885411145;41179;41247.79376103116;41453.58546414535;9464.526773661306;9617.326787124905;9647.90352096477;9658.3722058066;9697.175740062103;9495.95252023739;9590.712278631652;9604.183644390236;9607.231421486264;9632.662856800014;9615;9627.037296270388;9629.566669499889;9637.084476974676;9669.226461883067;8205.220504052106;8303.750887473385;9398.107179817905;9549.999999999965;9607.615695372177;8448.366372522085;9553.952230238874;9580;9588.952055239679;9713.174380177694;38980.83057508251;39057;39316.80341598302;39351;39502;2954.1875984104327;3203.263249452639;3374.173327534745;3529.29414117175;3701.555813302421;29682.55476167859;30050.24874378136;30190.396192076034;30278;30577.08268751941;3156.747460203201;3195.1852277669277;3207.3264614430996;3210.5665735125926;3281.0812972367776;469359;475289;478076;478432;478872;54623.90752185014;54846.451535484135;54955.175672364945;55017.72491137519;55109.1733623996;56434;56691.43308566944;56917;57110.143347849815;57401;15541.290458049605;15679.294431750599;15862.682746345008;15907.522774316789;15933.840661593467;12486.063545234143;12531.874681253254;12566.183198092094;12589.811152832715;12631.242125472501;14321.928390358189;14436.711265774626;14629.561113166623;14700.926495367668;14819.555413337617;4798.712077275374;6015.518758499309;6041.816439012676;6074.936886045013;6188.164597779242;861.5993562993239;939.4410325856105;989.9604015839286;1064.2337516987757;1066.6213494209599;25396.873015634803;29271.121866344045;29284.707152928197;29409.411811763646;29802.701972980427;910.2626872233512;968.7917097824002;1029.891861354547;1053.6048981631889;1059.4543809937888;12324.630261092181;15934.36262549485;16171.757423638655;16211.10838903848;16786.4124755633;548667;615044;619065;631960;633281;54338.64153396197;54361.45638543564;54482.72758636181;54575.72712136494;54643;1140.7319279969201;1213.417559571393;1277.5627419153452;1380.986190138106;1385.7852032934911;27904.30239243954;29632.555511667775;29761.5535766968;30345.39309213759;30445.695543044287;46862.53137468718;47122.52877471181;47213.763931180125;47282.52717472781;47382.52617473781;549641;555208;555497;555788;556475;24167.87916060479;24192.516149676554;24497.632535512337;24513.754862451147;24653.753462465145;43531.78234108936;44173.77913110414;44360.556394436935;44525.99633016457;44536.55463445324;29089.418211635224;36465.635343647285;37270.62729372671;37368;37645.435318469696;35834.462483062256;36160;36645.63354366529;36924.630753692116;37049.6295037057;24397;31411.74353025945;33582.152981586245;34106;34908.82545587357;29988.70011299859;32921.50617740688;33104.50343244901;33132.33735325232;33178.834105829315;21446.249381272206;24162.275131746075;24249.151279705125;24758.50482990294;25251.08317705432 05:24:18 INFO - PROCESS | 1938 | |2;jquery.html;352.2461931466662;365.91035196376873;378.2982567337587;378.5892306847071;383.2105861924436;18549.35077272296;18833.43499695008;19660.016999150022;20098.49753756157;20304.898475507638;14399.496017639358;16659.500644941952;19693.31073412431;19926.003699815024;20425.876576788272;4517.548245175489;5130.974345128269;5327.706976116312;5954.612950158247;6062.636241825505;5600.383957764663;5916.674582897939;5969.462748352647;5978.103284507337;5982.132590774335;5032;5822.097574875901;5885.823425297248;5907.113932910076;5911.408859114075;5482.616216864821;5898.88202235955;5911.9062973350065;5914.467697907209;5923.318818335861;4936.728479933602;5100.719460429675;5144.151215049513;5158.484151584849;5160.896782064376;24861;25196.622050669255;25212.991480340763;25318.49363012748;25512;2175.7824217578273;2292.495650956787;2481.85108893467;2525.9494810103697;2957.659869115058;15002.399904003827;15163.317650705634;15233.314500847488;15345.923270383688;15713;1777.7600023996724;1791.1939627167696;1824.7536582561318;1904.076522886399;1932.1691672580785;104124.47937760339;109949.45025274823;110129.89870101276;110540.44729776299;110661;9485.383450075724;10272;10369.844452333222;10507.737306567396;10910.454477276104;13172;13297.26865022433;13304.268265245504;13312.467501300032;13330.666733331744;7169.24722904098;8249.587520624065;8531.1895369941;8568.614412351459;8650.610722517376;8557.572121393905;8625.91374086251;8680.913190868137;8764.8685269721;8839.337049721296;6378.489720822323;6463.289038205794;6472.805815825533;6505.642189679613;6602.372774586406;1530.273120267886;1669.991650041742;1768.9469315920544;1825.6257467219189;1882.585831117152;861.6940985949898;883.8099808541194;909.490685216281;996.7757254617653;1007.6926537406015;21452.570948581255;22038.779612203994;22134.446638833837;22273;22350.441238969157;823.6540652925786;924.4869097650734;936.3024546712606;972.7227740094181;1025.179856115105;11103.666889993314;11477.942610286895;11633.185677002697;11662;11759.647210583526;504855;506842.4657876761;508180;536673;611662;118399.26402943785;122136;122970.15544766835;123223;127217;962.6341990043738;968.9079537443931;1026.3790406803926;1028.5011769291887;1035.7410647338015;13725.862741372857;14456.494022709154;14803.92598037003;15367.923160384127;16829.831701682826;58388.54028649148;58662;58758;59210.70394648171;59280;67107;82123;83330.33339333058;85644;86304.1369586296;6868.381845633717;7831.2560306770765;7999.680012799656;8001.879971800544;8248.092709801796;10520.158387328842;12850.871491284968;13141.34293285351;13213.933930330672;13247.933760331136;11057.723556911142;11136.55453781872;11218.439078045902;11250.437478126225;11288.153388495732;10881.183911206534;11326.537743478078;11338.433078345884;11365.147613929154;11514.596989105341;11131;11388.544458221579;11518.769624607628;11585.304881707125;11587.304761714324;6405.967970160119;7186.173590037212;7350.96324518374;7512.098548174035;7623.618819059136;9052.683156089506;11057.391843448688;11147.386893720923;11365.659030229106;11523.250988685708 05:24:18 INFO - PROCESS | 1938 | |3;mootools.html;1489.128859617124;1524.1312451902413;1737.3745451637408;1820.226403778394;1882.9058547072652;7937.563434011142;8188.467749596272;8319.584020798959;8910.42082264653;8924.330675199362;8393.53835539045;8680.782980425476;8684.652613895436;8796.636521339187;8828.33787465941;2115.0587988345114;2306.4349234437586;2343.8593684378993;2353.6587194856734;2358.3160883343808;2267.6258417361114;2296.5292115116363;2351.141833230867;2363.2555744940287;2378.643203519477;2339.2865176121277;2342.672025916373;2343.1915988983774;2358.716953965518;2363.7872591466767;2316.9073237070497;2319.826013049011;2332.9533409331802;2344.788968992782;2345.472268739537;2052.9076191571417;2243.170027089971;2320.559093772195;2326.6044772388736;2331.9650205246935;8689.608967596474;8814.382993190478;8854.247388971946;8857.645694172226;8872.600732967032;5154.92267615986;5285.86785330366;5312.76092575835;5324.361076670785;5331.840044798623;3469.6877281044576;3513.7891726496237;3612.566492020948;3671.761335513183;3674.8713795017047;1030.917751542954;1058.111186603257;1058.9682309530726;1078.6979645699216;1078.757279612089;10055.446950417723;10058.698239052841;11605.129615278887;11705.941470292679;11720.472578733978;4226.725262857966;4498.775061246924;4561.338605902192;4755.548222918817;4759.6668233223345;8345.332373410112;9928;9979.550920208609;9997.000299969945;10013.849792253122;5331.280277162611;5333.759980800874;5336.306280183629;5339.893202135935;5565.749541270652;4391.758453285036;4562.8631141065825;4592.540745925381;4813.326134341195;4901.754912254444;3399.1672040349777;3884.708646851441;3887.280853042196;3973.403989401555;4023.9195216095513;1278.8017857232144;1290.6966862787237;1296.6758310422395;1299.0711641176604;1302.5701518498872;912.3659056955485;918.4168053286078;935.3078721745859;944.990550094504;957.0190554681507;5437.429069947678;5909.054551271776;5965.612235204784;6163.9383606163365;6181.598196117238;415.3520508007518;426.19236546743747;428.2163640537808;430.95474975127786;435.3991491741377;3522.260325331709;3916.5691773904846;3964.742291751027;4074.2258970795456;4083.571225021389;10355.378677279386;10772.245942784086;10809.297395669413;10809.513571889283;10829.566817327219;10728.731781705364;10730.463476826128;10756.354618722746;10779.730506737395;10781.02970732622;6979.8604027919155;7965.203479652105;8167;8249.79375515605;8262.545559994258;5879.20630714848;5879.617824841543;5902.704864756831;5933.169356290036;5976.252968378953;9725.805483890425;9797.216222702024;9860.211183105192;9901.603935842486;9940.005999400002;9929.3545919515;10123.088921997056;10150.54322555472;10168.796624067625;10244.692659220234;6777.627230502172;6888;7165.319294666998;7575.886361704689;7608.619569021637;8322.70870519529;8379.162083791573;8470;8499.74500764978;8512.319014478702;7401.555906645618;7501.28737769911;7525.24747525265;7561.886571701539;7624.123225828879;7248.710051597877;7294.927050729425;7340.339369456775;7430.294122058396;7489.363404110712;6863.9656801717665;7010.50926435165;7019.508634395544;7023.262557431601;7030.894536581853;4493.393391892184;4728.983268597268;4930.235813448921;5101.846944591668;5109.284700141909;5023.723695196799;5045.678987665715;5111;5184;5226.503482169188 05:24:18 INFO - PROCESS | 1938 | |4;prototype.html;270.6684311718145;283.4501067928219;289.71607824332165;304.8948112901048;310.4195155060038;5157.974210128944;5365.248865158876;5395.679857661205;5420.593455490839;5473.91789123163;5336.733163341837;5348.518633323;5371.704556249405;5447.700376479302;5463.863403414906;3883.106885416349;3903;4052.533958594755;4084.530279017906;4172.913543228386;3424.6746559076973;4043.231785960673;4083.2241874043984;4084.8774536763945;4116.464859568252;3383.2556837495713;4097.078157414588;4111.958880411203;4122.732022418533;4172.123853990665;3735.5704094028993;4033.233685599756;4063.878083657495;4124.938125928114;4142.399352093945;3476.6870981611523;3484.059303987909;3500.2824420993634;3504.5794504659348;3531.0579181450357;5166.276721259027;5264.31563896691;5266.604349847294;5303.416624171338;5308.787648494055;3708.1471261609713;3895.5130608673917;3987.6442009716834;4269.530351661315;4660.650451216173;4830.6135509159185;4838.588719958808;4908.680361282085;5323.627346085776;5711.457411545896;3241.7082462578246;3344.5317655528;3675.467057276707;3813.31360355136;3824.5028146341074;5083.262926875619;5600.915986260209;5902.144189092601;5941.702914854283;6010.609310394854;3276.4921437177277;3827.158025234444;3837.865674701372;3910.843566257318;3917.9608203917596;3726.8509259629855;3923.372260438316;3964.841406343714;3971.4439978403047;3986.3023970804993;3862.6523612874416;4601.44782626087;4607.723536587815;4632.008236458355;4663.51033141522;4819.783109760069;5262.342207224097;5322.494363035506;5402.540784033401;5778.024317476419;1341.610932829488;1362.9727405451836;1369.5480491437802;1372.61566761307;1383.9515616953358;691.5746815708303;727.8617062758059;772.9227077292338;812.626191951697;834.9290310323569;4323.221820072355;4435.6229720473475;4844.442889067803;5100.668456550312;5246.685198888079;5184.507471790174;5362.168863826113;5724.055530837365;5937.061154728437;5999.430054144849;4420.8673739787855;4577.656675749332;4764.78558464863;4834.419869615598;5088.269988204029;2846.5445528715304;2869.9282517937218;2941.985015169763;3138.168385377877;3140.5917230759856;5185.403678576937;5434.619576629677;5963.910541341883;6069.6935164638035;6101.969490152521;5516.503514683698;5684.886302274014;5730;5981.37195594465;6013.45878870895;6084.201267416949;6112.982715703807;6128.111423843607;6130.099669102592;6221.666874921904;5044.722540260321;5081.517255860687;5648.068068768607;5871.354151043467;5890.823275301748;5816.534677225896;5829;5830.387809279964;5857.326407463196;5871.530277577698;6067.8435271838935;6089.908651370144;6133.26400831903;6169.9947517058035;6192.752289908533;3611.4041183204477;3861.362875125573;3861.5366156061455;3862.5751167371004;3875.6511913927884;5688.23664663939;5845.766169353178;5853.86818052357;5862.983856058613;5880.676562789087;5813.924423981577;5828.475437210502;5853.414658534112;5870.972579798604;5922.5261979040715;5514.476124768141;5732.942670573408;5741.45456181662;5741.655500669973;5798.956187886052;5655.7454914528125;5665;5671.015144699322;5703.686297253691;5727.942720572741;4770.809167633256;4812.735299558558;5446.482584154499;5636.844446888296;5718.285214348207;4653.673702994631;5040;5607.607467477401;5616.522595579421;5640.943590564206 05:24:18 INFO - PROCESS | 1938 | |5;yui.html;690.2890023276027;713.9607321597313;757.6174032113785;761.977140685779;796.6295672512282;14995.550133495999;16113.758293625575;16128.112953787566;16174.676506469877;16196.352145914165;14531.92734036331;14641;14661.706765864676;14674.706505869875;14689.559313220621;661.2032500836481;688.2738710660251;814.7963009247688;819.8319344534386;848.9915100849005;744.7989042958397;833.9791505212357;840.8402403543307;854.5513605357206;856.220839036477;742.9219931907153;824.1346586084617;907.2515174980667;908.582052255963;910.0171814440422;892.0989800301677;893.4907102951321;901.3870568013748;902.4900930973979;906.5557876640464;639.6545865232769;640.3948268885905;644;660.2341284110408;669.2838662630987;15290.617734556614;15294.541163765105;15407.30667119971;15575.688486230212;15589.766153507706;14050.43798248069;14476.565703028926;14663.533646635358;14681.706365872622;14720.411183552646;11760.941195294054;11894.702632434171;11999.580014699444;12045.39773011346;12048.879511204863;3743.457198706172;3790.1851102013197;3821.541415030214;3854.9807250963845;3932.0956180078742;85481;104560.47719761352;104694.47652761688;119784;120391;10900.563977440814;11001.284916480403;11070.557177712963;11108.666739997812;11167.441627918572;21314.254001110196;21321.933903304773;21334.466638333855;21351.572968540542;21358.893205533874;7716.7684969451;7960.442769006114;7972.162922893129;7973.401994850409;8153.184681531918;4314.827406903689;4443.866683999485;4496.460424748986;4536.909261814745;4538.364628951949;3182.236263296792;3559.4482855157494;3664.13892735207;3694.8522059117336;3705.6664900159117;660.247318057415;667.1094089390621;673.5554534007531;685.85939882324;689.7827184436886;542.3898114621052;552.8645481856971;632.3992207402894;650.8568115014689;679.4632240530025;8656.307495400353;9294.256459483226;9313.44119352841;9319.673811416567;9708.174805141502;542.3634530473167;604.6704546022428;615.8460384903774;660.9933900660932;671.7380221713536;4403.8238470461465;4414.646828253796;4688.296755486705;4697.906041879143;4741.2888066789565;88967.55516222506;91062;127708.36145819398;165209;166336.16831915762;141021.29489352694;142013;145234;145242;147796;122142;123272;124068.75931240752;124856.37571812083;124889.37555312166;7178.461615378972;8907.955460222658;8946.955265223633;8964.462132272085;9040.18581882659;47576;67302;68346.97479537911;68820.6558967202;69951.65024174846;78874.6056269715;79899;83269;92006.07993919976;95450;12953;13184.143030702973;13435.328233588478;13722;13786.241756703477;14499;14778.743806776114;14918.32867520986;15340.386384544494;15368.154751488328;11570.710732231424;12028;12339.444724987217;13858.307084645929;14328.856711432752;13364.331783410596;13631.38658760378;13765.242911639956;14078.944079193896;14258.42966281337;11310.434478276218;13062.725871137558;13573.253471058792;13711.862881371457;13712.971527135704;10806.243562950518;11122.132180088534;11175.944120279346;11249.437528123397;11341.659750207507;9535.713928582154;9771.706848794547;10145.746356341151;11399.658010259705;11569.247998880046 05:24:18 INFO - PROCESS | 1938 | __end_tp_report 05:24:18 INFO - PROCESS | 1938 | __start_cc_report 05:24:18 INFO - PROCESS | 1938 | _x_x_mozilla_cycle_collect,372 05:24:18 INFO - PROCESS | 1938 | __end_cc_report 05:24:18 INFO - PROCESS | 1938 | __startTimestamp1476447858283__endTimestamp 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | ------- Summary: start ------- 05:24:18 INFO - PROCESS | 1938 | Number of tests: 6 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | [#0] dojo.html Cycles:165 Average:5730.22 Median:5991.00 stddev:655.34 (10.9%) stddev-sans-first:622.39 05:24:18 INFO - PROCESS | 1938 | Values: 3037.8 3335.3 3359.3 3377.4 3390.7 5632.8 5652.8 5758.2 5796.4 5801.7 5731.9 5767.1 5786.2 5855.4 5888.9 5188.8 5204.8 5207.8 5340.5 5368.9 5303.3 5355.3 5356.3 5358.3 5441.6 5150.9 5194.1 5260.4 5305.3 5309.7 4936.7 5243.6 5271.8 5291.8 5300.4 5184.0 5211.5 5252.1 5253.6 5273.9 5624.3 5687.5 5733.8 5838.8 5846.8 6101.3 6115.2 6126.8 6137.6 6151.8 6219.4 6226.2 6230.3 6248.7 6250.7 5815.6 5818.6 5924.1 5934.1 5970.4 6235.2 6240.6 6245.1 6250.5 6267.3 6143.7 6152.3 6156.0 6157.0 6169.3 6097.0 6123.2 6133.0 6159.6 6212.6 6204.5 6205.9 6221.3 6223.2 6224.3 6083.3 6173.7 6227.8 6228.8 6239.7 4099.7 4878.9 4904.5 4915.2 4981.4 4396.5 4430.0 4446.7 4510.1 4547.0 5866.6 5901.6 5979.9 5994.2 5998.6 6119.9 6137.7 6164.0 6217.2 6222.5 5866.9 5926.3 5936.4 5983.8 5991.0 5426.5 5436.5 5443.2 5445.2 5460.8 6130.8 6204.2 6273.3 6298.5 6359.8 6141.5 6144.0 6147.8 6224.6 6226.3 6319.1 6322.2 6344.0 6348.2 6433.5 5121.8 6085.8 6100.9 6111.9 6214.3 6195.3 6207.1 6219.2 6223.7 6224.3 5932.1 6099.2 6134.7 6143.1 6157.9 4516.7 5104.0 5672.7 6042.8 6081.0 4847.4 5023.9 5965.2 6122.5 6129.4 6103.9 6142.5 6167.3 6168.3 6179.2 5869.9 5944.7 6049.6 6053.2 6069.5 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | [#1] ext.html Cycles:180 Average:66973.85 Median:39327.61 stddev:146956.09 (373.7%) stddev-sans-first:147283.44 05:24:18 INFO - PROCESS | 1938 | Values: 444.5 475.3 490.1 496.1 497.9 37765.0 39194.6 39338.4 41240.4 41312.0 32087.5 41114.6 41179.0 41247.8 41453.6 9464.5 9617.3 9647.9 9658.4 9697.2 9496.0 9590.7 9604.2 9607.2 9632.7 9615.0 9627.0 9629.6 9637.1 9669.2 8205.2 8303.8 9398.1 9550.0 9607.6 8448.4 9554.0 9580.0 9589.0 9713.2 38980.8 39057.0 39316.8 39351.0 39502.0 2954.2 3203.3 3374.2 3529.3 3701.6 29682.6 30050.2 30190.4 30278.0 30577.1 3156.7 3195.2 3207.3 3210.6 3281.1 469359.0 475289.0 478076.0 478432.0 478872.0 54623.9 54846.5 54955.2 55017.7 55109.2 56434.0 56691.4 56917.0 57110.1 57401.0 15541.3 15679.3 15862.7 15907.5 15933.8 12486.1 12531.9 12566.2 12589.8 12631.2 14321.9 14436.7 14629.6 14700.9 14819.6 4798.7 6015.5 6041.8 6074.9 6188.2 861.6 939.4 990.0 1064.2 1066.6 25396.9 29271.1 29284.7 29409.4 29802.7 910.3 968.8 1029.9 1053.6 1059.5 12324.6 15934.4 16171.8 16211.1 16786.4 548667.0 615044.0 619065.0 631960.0 633281.0 54338.6 54361.5 54482.7 54575.7 54643.0 1140.7 1213.4 1277.6 1381.0 1385.8 27904.3 29632.6 29761.6 30345.4 30445.7 46862.5 47122.5 47213.8 47282.5 47382.5 549641.0 555208.0 555497.0 555788.0 556475.0 24167.9 24192.5 24497.6 24513.8 24653.8 43531.8 44173.8 44360.6 44526.0 44536.6 29089.4 36465.6 37270.6 37368.0 37645.4 35834.5 36160.0 36645.6 36924.6 37049.6 24397.0 31411.7 33582.2 34106.0 34908.8 29988.7 32921.5 33104.5 33132.3 33178.8 21446.2 24162.3 24249.2 24758.5 25251.1 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | [#2] jquery.html Cycles:180 Average:33019.46 Median:13671.43 stddev:89704.94 (656.1%) stddev-sans-first:89923.05 05:24:18 INFO - PROCESS | 1938 | Values: 352.2 365.9 378.3 378.6 383.2 18549.4 18833.4 19660.0 20098.5 20304.9 14399.5 16659.5 19693.3 19926.0 20425.9 4517.5 5131.0 5327.7 5954.6 6062.6 5600.4 5916.7 5969.5 5978.1 5982.1 5032.0 5822.1 5885.8 5907.1 5911.4 5482.6 5898.9 5911.9 5914.5 5923.3 4936.7 5100.7 5144.2 5158.5 5160.9 24861.0 25196.6 25213.0 25318.5 25512.0 2175.8 2292.5 2481.9 2525.9 2957.7 15002.4 15163.3 15233.3 15345.9 15713.0 1777.8 1791.2 1824.8 1904.1 1932.2 104124.5 109949.5 110129.9 110540.4 110661.0 9485.4 10272.0 10369.8 10507.7 10910.5 13172.0 13297.3 13304.3 13312.5 13330.7 7169.2 8249.6 8531.2 8568.6 8650.6 8557.6 8625.9 8680.9 8764.9 8839.3 6378.5 6463.3 6472.8 6505.6 6602.4 1530.3 1670.0 1768.9 1825.6 1882.6 861.7 883.8 909.5 996.8 1007.7 21452.6 22038.8 22134.4 22273.0 22350.4 823.7 924.5 936.3 972.7 1025.2 11103.7 11477.9 11633.2 11662.0 11759.6 504855.0 506842.5 508180.0 536673.0 611662.0 118399.3 122136.0 122970.2 123223.0 127217.0 962.6 968.9 1026.4 1028.5 1035.7 13725.9 14456.5 14803.9 15367.9 16829.8 58388.5 58662.0 58758.0 59210.7 59280.0 67107.0 82123.0 83330.3 85644.0 86304.1 6868.4 7831.3 7999.7 8001.9 8248.1 10520.2 12850.9 13141.3 13213.9 13247.9 11057.7 11136.6 11218.4 11250.4 11288.2 10881.2 11326.5 11338.4 11365.1 11514.6 11131.0 11388.5 11518.8 11585.3 11587.3 6406.0 7186.2 7351.0 7512.1 7623.6 9052.7 11057.4 11147.4 11365.7 11523.3 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | [#3] mootools.html Cycles:180 Average:5653.51 Median:4857.54 stddev:3179.43 (65.5%) stddev-sans-first:3172.95 05:24:18 INFO - PROCESS | 1938 | Values: 1489.1 1524.1 1737.4 1820.2 1882.9 7937.6 8188.5 8319.6 8910.4 8924.3 8393.5 8680.8 8684.7 8796.6 8828.3 2115.1 2306.4 2343.9 2353.7 2358.3 2267.6 2296.5 2351.1 2363.3 2378.6 2339.3 2342.7 2343.2 2358.7 2363.8 2316.9 2319.8 2333.0 2344.8 2345.5 2052.9 2243.2 2320.6 2326.6 2332.0 8689.6 8814.4 8854.2 8857.6 8872.6 5154.9 5285.9 5312.8 5324.4 5331.8 3469.7 3513.8 3612.6 3671.8 3674.9 1030.9 1058.1 1059.0 1078.7 1078.8 10055.4 10058.7 11605.1 11705.9 11720.5 4226.7 4498.8 4561.3 4755.5 4759.7 8345.3 9928.0 9979.6 9997.0 10013.8 5331.3 5333.8 5336.3 5339.9 5565.7 4391.8 4562.9 4592.5 4813.3 4901.8 3399.2 3884.7 3887.3 3973.4 4023.9 1278.8 1290.7 1296.7 1299.1 1302.6 912.4 918.4 935.3 945.0 957.0 5437.4 5909.1 5965.6 6163.9 6181.6 415.4 426.2 428.2 431.0 435.4 3522.3 3916.6 3964.7 4074.2 4083.6 10355.4 10772.2 10809.3 10809.5 10829.6 10728.7 10730.5 10756.4 10779.7 10781.0 6979.9 7965.2 8167.0 8249.8 8262.5 5879.2 5879.6 5902.7 5933.2 5976.3 9725.8 9797.2 9860.2 9901.6 9940.0 9929.4 10123.1 10150.5 10168.8 10244.7 6777.6 6888.0 7165.3 7575.9 7608.6 8322.7 8379.2 8470.0 8499.7 8512.3 7401.6 7501.3 7525.2 7561.9 7624.1 7248.7 7294.9 7340.3 7430.3 7489.4 6864.0 7010.5 7019.5 7023.3 7030.9 4493.4 4729.0 4930.2 5101.8 5109.3 5023.7 5045.7 5111.0 5184.0 5226.5 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | [#4] prototype.html Cycles:180 Average:4574.15 Median:5162.13 stddev:1447.10 (28.0%) stddev-sans-first:1414.65 05:24:18 INFO - PROCESS | 1938 | Values: 270.7 283.5 289.7 304.9 310.4 5158.0 5365.2 5395.7 5420.6 5473.9 5336.7 5348.5 5371.7 5447.7 5463.9 3883.1 3903.0 4052.5 4084.5 4172.9 3424.7 4043.2 4083.2 4084.9 4116.5 3383.3 4097.1 4112.0 4122.7 4172.1 3735.6 4033.2 4063.9 4124.9 4142.4 3476.7 3484.1 3500.3 3504.6 3531.1 5166.3 5264.3 5266.6 5303.4 5308.8 3708.1 3895.5 3987.6 4269.5 4660.7 4830.6 4838.6 4908.7 5323.6 5711.5 3241.7 3344.5 3675.5 3813.3 3824.5 5083.3 5600.9 5902.1 5941.7 6010.6 3276.5 3827.2 3837.9 3910.8 3918.0 3726.9 3923.4 3964.8 3971.4 3986.3 3862.7 4601.4 4607.7 4632.0 4663.5 4819.8 5262.3 5322.5 5402.5 5778.0 1341.6 1363.0 1369.5 1372.6 1384.0 691.6 727.9 772.9 812.6 834.9 4323.2 4435.6 4844.4 5100.7 5246.7 5184.5 5362.2 5724.1 5937.1 5999.4 4420.9 4577.7 4764.8 4834.4 5088.3 2846.5 2869.9 2942.0 3138.2 3140.6 5185.4 5434.6 5963.9 6069.7 6102.0 5516.5 5684.9 5730.0 5981.4 6013.5 6084.2 6113.0 6128.1 6130.1 6221.7 5044.7 5081.5 5648.1 5871.4 5890.8 5816.5 5829.0 5830.4 5857.3 5871.5 6067.8 6089.9 6133.3 6170.0 6192.8 3611.4 3861.4 3861.5 3862.6 3875.7 5688.2 5845.8 5853.9 5863.0 5880.7 5813.9 5828.5 5853.4 5871.0 5922.5 5514.5 5732.9 5741.5 5741.7 5799.0 5655.7 5665.0 5671.0 5703.7 5727.9 4770.8 4812.7 5446.5 5636.8 5718.3 4653.7 5040.0 5607.6 5616.5 5640.9 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - PROCESS | 1938 | [#5] yui.html Cycles:180 Average:24909.71 Median:3766.82 stddev:40134.78 (1065.5%) stddev-sans-first:40206.17 05:24:18 INFO - PROCESS | 1938 | Values: 690.3 714.0 757.6 762.0 796.6 14995.6 16113.8 16128.1 16174.7 16196.4 14531.9 14641.0 14661.7 14674.7 14689.6 661.2 688.3 814.8 819.8 849.0 744.8 834.0 840.8 854.6 856.2 742.9 824.1 907.3 908.6 910.0 892.1 893.5 901.4 902.5 906.6 639.7 640.4 644.0 660.2 669.3 15290.6 15294.5 15407.3 15575.7 15589.8 14050.4 14476.6 14663.5 14681.7 14720.4 11760.9 11894.7 11999.6 12045.4 12048.9 3743.5 3790.2 3821.5 3855.0 3932.1 85481.0 104560.5 104694.5 119784.0 120391.0 10900.6 11001.3 11070.6 11108.7 11167.4 21314.3 21321.9 21334.5 21351.6 21358.9 7716.8 7960.4 7972.2 7973.4 8153.2 4314.8 4443.9 4496.5 4536.9 4538.4 3182.2 3559.4 3664.1 3694.9 3705.7 660.2 667.1 673.6 685.9 689.8 542.4 552.9 632.4 650.9 679.5 8656.3 9294.3 9313.4 9319.7 9708.2 542.4 604.7 615.8 661.0 671.7 4403.8 4414.6 4688.3 4697.9 4741.3 88967.6 91062.0 127708.4 165209.0 166336.2 141021.3 142013.0 145234.0 145242.0 147796.0 122142.0 123272.0 124068.8 124856.4 124889.4 7178.5 8908.0 8947.0 8964.5 9040.2 47576.0 67302.0 68347.0 68820.7 69951.7 78874.6 79899.0 83269.0 92006.1 95450.0 12953.0 13184.1 13435.3 13722.0 13786.2 14499.0 14778.7 14918.3 15340.4 15368.2 11570.7 12028.0 12339.4 13858.3 14328.9 13364.3 13631.4 13765.2 14078.9 14258.4 11310.4 13062.7 13573.3 13711.9 13713.0 10806.2 11122.1 11175.9 11249.4 11341.7 9535.7 9771.7 10145.7 11399.7 11569.2 05:24:18 INFO - PROCESS | 1938 | -------- Summary: end -------- 05:24:18 INFO - PROCESS | 1938 | 05:24:18 INFO - TEST-INFO | 1938: exit 0 05:24:18 INFO - TEST-OK | dromaeo_css | took 1115884ms 05:24:18 INFO - TEST-START | kraken 05:24:18 INFO - operating with platform_type : mac_ 05:24:18 INFO - Initialising browser for kraken test... 05:24:18 INFO - TEST-INFO | started process 1943 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpa35bmK/profile http://localhost:52122/getInfo.html) 05:24:21 INFO - PROCESS | 1943 | 2016-10-14 05:24:21.453 plugin-container[1944:17969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 05:24:21 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:21 INFO - PROCESS | 1943 | 2016-10-14 05:24:21.456 plugin-container[1944:17969] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 05:24:21 INFO - PROCESS | 1943 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:21 INFO - PROCESS | 1943 | 2016-10-14 05:24:21.456 plugin-container[1944:17969] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:21 INFO - PROCESS | 1943 | __metrics Screen width/height:1600/1200 05:24:21 INFO - PROCESS | 1943 | colorDepth:24 05:24:21 INFO - PROCESS | 1943 | Browser inner width/height: 1024/689 05:24:21 INFO - PROCESS | 1943 | __metrics 05:24:21 ERROR - PROCESS | 1943 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 05:24:22 INFO - TEST-INFO | 1943: exit 0 05:24:22 INFO - Browser initialized. 05:24:22 INFO - Running cycle 1/1 for kraken test... 05:24:22 INFO - TEST-INFO | started process 1945 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/T/tmpa35bmK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:24:24 INFO - PROCESS | 1945 | 2016-10-14 05:24:24.421 plugin-container[1946:18084] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 05:24:24 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:24 INFO - PROCESS | 1945 | 2016-10-14 05:24:24.422 plugin-container[1946:18084] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 05:24:24 INFO - PROCESS | 1945 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:24:24 INFO - PROCESS | 1945 | 2016-10-14 05:24:24.422 plugin-container[1946:18084] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:24:34 INFO - PROCESS | 1945 | RSS: Main: 239276032 05:24:34 INFO - PROCESS | 1945 | 05:24:36 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52122/tests/kraken/driver.html?testName=audio-beat-detection) 05:24:37 INFO - PROCESS | 1945 | RSS: Main: 252583936 05:24:37 INFO - PROCESS | 1945 | 05:24:40 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52122/tests/kraken/driver.html?testName=audio-dft) 05:24:41 INFO - PROCESS | 1945 | RSS: Main: 261095424 05:24:41 INFO - PROCESS | 1945 | 05:24:44 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52122/tests/kraken/driver.html?testName=audio-fft) 05:24:45 INFO - PROCESS | 1945 | RSS: Main: 260898816 05:24:45 INFO - PROCESS | 1945 | 05:24:47 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52122/tests/kraken/driver.html?testName=audio-oscillator) 05:24:47 INFO - PROCESS | 1945 | RSS: Main: 263417856 05:24:47 INFO - PROCESS | 1945 | 05:24:49 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52122/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:24:50 INFO - PROCESS | 1945 | RSS: Main: 263548928 05:24:50 INFO - PROCESS | 1945 | 05:24:53 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52122/tests/kraken/driver.html?testName=imaging-darkroom) 05:24:53 INFO - PROCESS | 1945 | RSS: Main: 264548352 05:24:53 INFO - PROCESS | 1945 | 05:24:56 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52122/tests/kraken/driver.html?testName=imaging-desaturate) 05:24:57 INFO - PROCESS | 1945 | RSS: Main: 264499200 05:24:57 INFO - PROCESS | 1945 | 05:25:00 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52122/tests/kraken/driver.html?testName=json-parse-financial) 05:25:01 INFO - PROCESS | 1945 | RSS: Main: 265097216 05:25:01 INFO - PROCESS | 1945 | 05:25:02 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52122/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:25:03 INFO - PROCESS | 1945 | RSS: Main: 264216576 05:25:03 INFO - PROCESS | 1945 | 05:25:05 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:25:06 INFO - PROCESS | 1945 | RSS: Main: 264286208 05:25:06 INFO - PROCESS | 1945 | 05:25:08 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:25:08 INFO - PROCESS | 1945 | RSS: Main: 264142848 05:25:08 INFO - PROCESS | 1945 | 05:25:11 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:25:12 INFO - PROCESS | 1945 | RSS: Main: 264433664 05:25:12 INFO - PROCESS | 1945 | 05:25:15 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:25:16 INFO - PROCESS | 1945 | RSS: Main: 264519680 05:25:16 INFO - PROCESS | 1945 | 05:25:17 INFO - PROCESS | 1945 | Cycle 1(1): loaded http://localhost:52122/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52122/tests/kraken/driver.html?testName=ai-astar) 05:25:18 INFO - PROCESS | 1945 | RSS: Main: 266199040 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | __start_tp_report 05:25:18 INFO - PROCESS | 1945 | _x_x_mozilla_page_load 05:25:18 INFO - PROCESS | 1945 | _x_x_mozilla_page_load_details 05:25:18 INFO - PROCESS | 1945 | |i|pagename|runs| 05:25:18 INFO - PROCESS | 1945 | |0;ai-astar;109;120;141;171;117;129;120;118;118;119 05:25:18 INFO - PROCESS | 1945 | |1;audio-beat-detection;153;135;113;152;116;116;129;115;143;116 05:25:18 INFO - PROCESS | 1945 | |2;audio-dft;194;201;196;238;275;236;193;197;195;194 05:25:18 INFO - PROCESS | 1945 | |3;audio-fft;81;60;57;83;61;59;61;71;105;83 05:25:18 INFO - PROCESS | 1945 | |4;audio-oscillator;81;73;89;74;68;71;89;68;71;87 05:25:18 INFO - PROCESS | 1945 | |5;imaging-gaussian-blur;93;90;93;90;94;88;94;76;135;132 05:25:18 INFO - PROCESS | 1945 | |6;imaging-darkroom;98;130;119;98;98;98;98;96;89;99 05:25:18 INFO - PROCESS | 1945 | |7;imaging-desaturate;141;96;122;129;138;131;99;87;77;125 05:25:18 INFO - PROCESS | 1945 | |8;json-parse-financial;57;60;56;57;64;88;49;54;56;88 05:25:18 INFO - PROCESS | 1945 | |9;json-stringify-tinderbox;69;73;77;80;84;59;89;102;105;88 05:25:18 INFO - PROCESS | 1945 | |10;stanford-crypto-aes;87;86;88;92;88;86;91;88;90;93 05:25:18 INFO - PROCESS | 1945 | |11;stanford-crypto-ccm;145;170;141;142;123;158;167;167;199;171 05:25:18 INFO - PROCESS | 1945 | |12;stanford-crypto-pbkdf2;175;175;177;173;175;179;223;174;173;172 05:25:18 INFO - PROCESS | 1945 | |13;stanford-crypto-sha256-iterative;69;68;71;69;71;71;70;83;75;68 05:25:18 INFO - PROCESS | 1945 | __end_tp_report 05:25:18 INFO - PROCESS | 1945 | __start_cc_report 05:25:18 INFO - PROCESS | 1945 | _x_x_mozilla_cycle_collect,945 05:25:18 INFO - PROCESS | 1945 | __end_cc_report 05:25:18 INFO - PROCESS | 1945 | __startTimestamp1476447918165__endTimestamp 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | ------- Summary: start ------- 05:25:18 INFO - PROCESS | 1945 | Number of tests: 14 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#0] ai-astar Cycles:10 Average:126.20 Median:120.00 stddev:17.88 (14.9%) stddev-sans-first:17.85 05:25:18 INFO - PROCESS | 1945 | Values: 109.0 120.0 141.0 171.0 117.0 129.0 120.0 118.0 118.0 119.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#1] audio-beat-detection Cycles:10 Average:128.80 Median:132.00 stddev:15.97 (12.1%) stddev-sans-first:14.34 05:25:18 INFO - PROCESS | 1945 | Values: 153.0 135.0 113.0 152.0 116.0 116.0 129.0 115.0 143.0 116.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#2] audio-dft Cycles:10 Average:211.90 Median:199.00 stddev:28.13 (14.1%) stddev-sans-first:29.08 05:25:18 INFO - PROCESS | 1945 | Values: 194.0 201.0 196.0 238.0 275.0 236.0 193.0 197.0 195.0 194.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#3] audio-fft Cycles:10 Average:72.10 Median:66.00 stddev:15.61 (23.7%) stddev-sans-first:16.22 05:25:18 INFO - PROCESS | 1945 | Values: 81.0 60.0 57.0 83.0 61.0 59.0 61.0 71.0 105.0 83.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:77.50 stddev:8.58 (11.1%) stddev-sans-first:8.99 05:25:18 INFO - PROCESS | 1945 | Values: 81.0 73.0 89.0 74.0 68.0 71.0 89.0 68.0 71.0 87.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#5] imaging-gaussian-blur Cycles:10 Average:98.50 Median:91.50 stddev:19.20 (21.0%) stddev-sans-first:20.26 05:25:18 INFO - PROCESS | 1945 | Values: 93.0 90.0 93.0 90.0 94.0 88.0 94.0 76.0 135.0 132.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#6] imaging-darkroom Cycles:10 Average:102.30 Median:98.00 stddev:12.32 (12.6%) stddev-sans-first:12.97 05:25:18 INFO - PROCESS | 1945 | Values: 98.0 130.0 119.0 98.0 98.0 98.0 98.0 96.0 89.0 99.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#7] imaging-desaturate Cycles:10 Average:114.50 Median:109.00 stddev:22.73 (20.9%) stddev-sans-first:21.99 05:25:18 INFO - PROCESS | 1945 | Values: 141.0 96.0 122.0 129.0 138.0 131.0 99.0 87.0 77.0 125.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#8] json-parse-financial Cycles:10 Average:62.90 Median:58.50 stddev:13.77 (23.5%) stddev-sans-first:14.44 05:25:18 INFO - PROCESS | 1945 | Values: 57.0 60.0 56.0 57.0 64.0 88.0 49.0 54.0 56.0 88.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#9] json-stringify-tinderbox Cycles:10 Average:82.60 Median:78.50 stddev:14.23 (18.1%) stddev-sans-first:14.22 05:25:18 INFO - PROCESS | 1945 | Values: 69.0 73.0 77.0 80.0 84.0 59.0 89.0 102.0 105.0 88.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#10] stanford-crypto-aes Cycles:10 Average:88.90 Median:89.00 stddev:2.47 (2.8%) stddev-sans-first:2.52 05:25:18 INFO - PROCESS | 1945 | Values: 87.0 86.0 88.0 92.0 88.0 86.0 91.0 88.0 90.0 93.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#11] stanford-crypto-ccm Cycles:10 Average:158.30 Median:167.00 stddev:21.33 (12.8%) stddev-sans-first:22.07 05:25:18 INFO - PROCESS | 1945 | Values: 145.0 170.0 141.0 142.0 123.0 158.0 167.0 167.0 199.0 171.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.60 Median:175.00 stddev:15.39 (8.8%) stddev-sans-first:16.23 05:25:18 INFO - PROCESS | 1945 | Values: 175.0 175.0 177.0 173.0 175.0 179.0 223.0 174.0 173.0 172.0 05:25:18 INFO - PROCESS | 1945 | 05:25:18 INFO - PROCESS | 1945 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.50 Median:71.00 stddev:4.53 (6.4%) stddev-sans-first:4.71 05:25:18 INFO - PROCESS | 1945 | Values: 69.0 68.0 71.0 69.0 71.0 71.0 70.0 83.0 75.0 68.0 05:25:18 INFO - PROCESS | 1945 | -------- Summary: end -------- 05:25:18 INFO - PROCESS | 1945 | 05:25:19 INFO - TEST-INFO | 1945: exit 0 05:25:19 INFO - TEST-OK | kraken | took 60208ms 05:25:19 INFO - SUITE-END | took 1176s 05:25:19 INFO - Completed test suite (00:19:36) 05:25:19 INFO - javascript benchmark 05:25:19 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7256.806920338189, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3037.7721670874685, 3335.2995870867117, 3359.2609625882315, 3377.408953433149, 3390.7456940729453, 5632.7746890124345, 5652.830415087549, 5758.165066065423, 5796.391378905218, 5801.6519008859395, 5731.942680573204, 5767.10609855472, 5786.189933409316, 5855.443732845383, 5888.941110588893, 5188.792448302063, 5204.76578553966, 5207.765650545734, 5340.54605358545, 5368.892622147574, 5303.337082864642, 5355.277037599933, 5356.276902618136, 5358.2766326546, 5441.646292990943, 5150.922736158961, 5194.065068287707, 5260.421353651095, 5305.310309659758, 5309.654872433318, 4936.728479933602, 5243.580513558944, 5271.762770675329, 5291.761870715827, 5300.3639563252655, 5183.974080129613, 5211.478852114796, 5252.080885844962, 5253.579713622939, 5273.894522109575, 5624.268845050117, 5687.4881260686325, 5733.827985160452, 5838.766449342022, 5846.824595262149, 6101.277634935909, 6115.240341084353, 6126.827321259284, 6137.631742095444, 6151.753929842801, 6219.378062193744, 6226.159468471744, 6230.314665386803, 6248.718807653667, 6250.718717657716, 5815.563832712563, 5818.6217895836635, 5924.052151655715, 5934.1098835174635, 5970.402959704082, 6235.189425374763, 6240.594361366497, 6245.0632405139595, 6250.531210159256, 6267.2792628847355, 6143.662098584621, 6152.261728592597, 6156.0, 6157.0, 6169.321374648785, 6097.0105204680185, 6123.173371594867, 6132.998044146438, 6159.630422174684, 6212.565120441525, 6204.472619827366, 6205.937940620626, 6221.3156552779155, 6223.222097237845, 6224.346443623354, 6083.330833608388, 6173.722182501798, 6227.782027629011, 6228.813135605939, 6239.719212635442, 4099.651529620016, 4878.902421951541, 4904.485029071968, 4915.238138088602, 4981.352424184833, 4396.516383197845, 4429.9778501107285, 4446.73319600825, 4510.120526497301, 4547.0, 5866.6480011199465, 5901.616394934315, 5979.880402392015, 5994.190784244071, 5998.640081595118, 6119.8681629442035, 6137.683438940172, 6164.044573091178, 6217.191765070603, 6222.533310001768, 5866.911996320058, 5926.288845338587, 5936.406359364116, 5983.820485385445, 5991.0, 5426.511613954763, 5436.537894278951, 5443.183522471661, 5445.156000819879, 5460.7542660579575, 6130.795626845537, 6204.224471941007, 6273.278572964167, 6298.464630506458, 6359.777407790705, 6141.547378150805, 6143.96928015357, 6147.84630384244, 6224.62652240867, 6226.3151053385, 6319.146915166578, 6322.178116844781, 6344.016677415008, 6348.238211414659, 6433.517486188461, 5121.8207362743615, 6085.782843431243, 6100.914243738686, 6111.930412177762, 6214.285357183981, 6195.31851496326, 6207.068939659087, 6219.222597175353, 6223.657698826608, 6224.346443623354, 5932.139839723302, 6099.176611157437, 6134.693265336626, 6143.139960405458, 6157.907631385622, 4516.661250406299, 5104.0, 5672.659640421588, 6042.788502402394, 6081.013867059388, 4847.442544107473, 5023.924641130459, 5965.194698715618, 6122.510199184144, 6129.387061293835, 6103.8779224414375, 6142.539309551712, 6167.321594624498, 6168.290646575521, 6179.16581261524, 5869.882602348014, 5944.673042982505, 6049.606775559574, 6053.213082299273, 6069.514438844793], "value": 5688.542444421753, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [444.53768081195557, 475.26096919290535, 490.0688691486177, 496.06739330059503, 497.94771548987353, 37765.0, 39194.60805391945, 39338.409923851126, 41240.38139427911, 41312.0, 32087.518687219712, 41114.58885411145, 41179.0, 41247.79376103116, 41453.58546414535, 9464.526773661306, 9617.326787124905, 9647.90352096477, 9658.3722058066, 9697.175740062103, 9495.95252023739, 9590.712278631652, 9604.183644390236, 9607.231421486264, 9632.662856800014, 9615.0, 9627.037296270388, 9629.566669499889, 9637.084476974676, 9669.226461883067, 8205.220504052106, 8303.750887473385, 9398.107179817905, 9549.999999999965, 9607.615695372177, 8448.366372522085, 9553.952230238874, 9580.0, 9588.952055239679, 9713.174380177694, 38980.83057508251, 39057.0, 39316.80341598302, 39351.0, 39502.0, 2954.1875984104327, 3203.263249452639, 3374.173327534745, 3529.29414117175, 3701.555813302421, 29682.55476167859, 30050.24874378136, 30190.396192076034, 30278.0, 30577.08268751941, 3156.747460203201, 3195.1852277669277, 3207.3264614430996, 3210.5665735125926, 3281.0812972367776, 469359.0, 475289.0, 478076.0, 478432.0, 478872.0, 54623.90752185014, 54846.451535484135, 54955.175672364945, 55017.72491137519, 55109.1733623996, 56434.0, 56691.43308566944, 56917.0, 57110.143347849815, 57401.0, 15541.290458049605, 15679.294431750599, 15862.682746345008, 15907.522774316789, 15933.840661593467, 12486.063545234143, 12531.874681253254, 12566.183198092094, 12589.811152832715, 12631.242125472501, 14321.928390358189, 14436.711265774626, 14629.561113166623, 14700.926495367668, 14819.555413337617, 4798.712077275374, 6015.518758499309, 6041.816439012676, 6074.936886045013, 6188.164597779242, 861.5993562993239, 939.4410325856105, 989.9604015839286, 1064.2337516987757, 1066.6213494209599, 25396.873015634803, 29271.121866344045, 29284.707152928197, 29409.411811763646, 29802.701972980427, 910.2626872233512, 968.7917097824002, 1029.891861354547, 1053.6048981631889, 1059.4543809937888, 12324.630261092181, 15934.36262549485, 16171.757423638655, 16211.10838903848, 16786.4124755633, 548667.0, 615044.0, 619065.0, 631960.0, 633281.0, 54338.64153396197, 54361.45638543564, 54482.72758636181, 54575.72712136494, 54643.0, 1140.7319279969201, 1213.417559571393, 1277.5627419153452, 1380.986190138106, 1385.7852032934911, 27904.30239243954, 29632.555511667775, 29761.5535766968, 30345.39309213759, 30445.695543044287, 46862.53137468718, 47122.52877471181, 47213.763931180125, 47282.52717472781, 47382.52617473781, 549641.0, 555208.0, 555497.0, 555788.0, 556475.0, 24167.87916060479, 24192.516149676554, 24497.632535512337, 24513.754862451147, 24653.753462465145, 43531.78234108936, 44173.77913110414, 44360.556394436935, 44525.99633016457, 44536.55463445324, 29089.418211635224, 36465.635343647285, 37270.62729372671, 37368.0, 37645.435318469696, 35834.462483062256, 36160.0, 36645.63354366529, 36924.630753692116, 37049.6295037057, 24397.0, 31411.74353025945, 33582.152981586245, 34106.0, 34908.82545587357, 29988.70011299859, 32921.50617740688, 33104.50343244901, 33132.33735325232, 33178.834105829315, 21446.249381272206, 24162.275131746075, 24249.151279705125, 24758.50482990294, 25251.08317705432], "value": 18905.00045914789, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.2461931466662, 365.91035196376873, 378.2982567337587, 378.5892306847071, 383.2105861924436, 18549.35077272296, 18833.43499695008, 19660.016999150022, 20098.49753756157, 20304.898475507638, 14399.496017639358, 16659.500644941952, 19693.31073412431, 19926.003699815024, 20425.876576788272, 4517.548245175489, 5130.974345128269, 5327.706976116312, 5954.612950158247, 6062.636241825505, 5600.383957764663, 5916.674582897939, 5969.462748352647, 5978.103284507337, 5982.132590774335, 5032.0, 5822.097574875901, 5885.823425297248, 5907.113932910076, 5911.408859114075, 5482.616216864821, 5898.88202235955, 5911.9062973350065, 5914.467697907209, 5923.318818335861, 4936.728479933602, 5100.719460429675, 5144.151215049513, 5158.484151584849, 5160.896782064376, 24861.0, 25196.622050669255, 25212.991480340763, 25318.49363012748, 25512.0, 2175.7824217578273, 2292.495650956787, 2481.85108893467, 2525.9494810103697, 2957.659869115058, 15002.399904003827, 15163.317650705634, 15233.314500847488, 15345.923270383688, 15713.0, 1777.7600023996724, 1791.1939627167696, 1824.7536582561318, 1904.076522886399, 1932.1691672580785, 104124.47937760339, 109949.45025274823, 110129.89870101276, 110540.44729776299, 110661.0, 9485.383450075724, 10272.0, 10369.844452333222, 10507.737306567396, 10910.454477276104, 13172.0, 13297.26865022433, 13304.268265245504, 13312.467501300032, 13330.666733331744, 7169.24722904098, 8249.587520624065, 8531.1895369941, 8568.614412351459, 8650.610722517376, 8557.572121393905, 8625.91374086251, 8680.913190868137, 8764.8685269721, 8839.337049721296, 6378.489720822323, 6463.289038205794, 6472.805815825533, 6505.642189679613, 6602.372774586406, 1530.273120267886, 1669.991650041742, 1768.9469315920544, 1825.6257467219189, 1882.585831117152, 861.6940985949898, 883.8099808541194, 909.490685216281, 996.7757254617653, 1007.6926537406015, 21452.570948581255, 22038.779612203994, 22134.446638833837, 22273.0, 22350.441238969157, 823.6540652925786, 924.4869097650734, 936.3024546712606, 972.7227740094181, 1025.179856115105, 11103.666889993314, 11477.942610286895, 11633.185677002697, 11662.0, 11759.647210583526, 504855.0, 506842.4657876761, 508180.0, 536673.0, 611662.0, 118399.26402943785, 122136.0, 122970.15544766835, 123223.0, 127217.0, 962.6341990043738, 968.9079537443931, 1026.3790406803926, 1028.5011769291887, 1035.7410647338015, 13725.862741372857, 14456.494022709154, 14803.92598037003, 15367.923160384127, 16829.831701682826, 58388.54028649148, 58662.0, 58758.0, 59210.70394648171, 59280.0, 67107.0, 82123.0, 83330.33339333058, 85644.0, 86304.1369586296, 6868.381845633717, 7831.2560306770765, 7999.680012799656, 8001.879971800544, 8248.092709801796, 10520.158387328842, 12850.871491284968, 13141.34293285351, 13213.933930330672, 13247.933760331136, 11057.723556911142, 11136.55453781872, 11218.439078045902, 11250.437478126225, 11288.153388495732, 10881.183911206534, 11326.537743478078, 11338.433078345884, 11365.147613929154, 11514.596989105341, 11131.0, 11388.544458221579, 11518.769624607628, 11585.304881707125, 11587.304761714324, 6405.967970160119, 7186.173590037212, 7350.96324518374, 7512.098548174035, 7623.618819059136, 9052.683156089506, 11057.391843448688, 11147.386893720923, 11365.659030229106, 11523.250988685708], "value": 9530.061613337002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.128859617124, 1524.1312451902413, 1737.3745451637408, 1820.226403778394, 1882.9058547072652, 7937.563434011142, 8188.467749596272, 8319.584020798959, 8910.42082264653, 8924.330675199362, 8393.53835539045, 8680.782980425476, 8684.652613895436, 8796.636521339187, 8828.33787465941, 2115.0587988345114, 2306.4349234437586, 2343.8593684378993, 2353.6587194856734, 2358.3160883343808, 2267.6258417361114, 2296.5292115116363, 2351.141833230867, 2363.2555744940287, 2378.643203519477, 2339.2865176121277, 2342.672025916373, 2343.1915988983774, 2358.716953965518, 2363.7872591466767, 2316.9073237070497, 2319.826013049011, 2332.9533409331802, 2344.788968992782, 2345.472268739537, 2052.9076191571417, 2243.170027089971, 2320.559093772195, 2326.6044772388736, 2331.9650205246935, 8689.608967596474, 8814.382993190478, 8854.247388971946, 8857.645694172226, 8872.600732967032, 5154.92267615986, 5285.86785330366, 5312.76092575835, 5324.361076670785, 5331.840044798623, 3469.6877281044576, 3513.7891726496237, 3612.566492020948, 3671.761335513183, 3674.8713795017047, 1030.917751542954, 1058.111186603257, 1058.9682309530726, 1078.6979645699216, 1078.757279612089, 10055.446950417723, 10058.698239052841, 11605.129615278887, 11705.941470292679, 11720.472578733978, 4226.725262857966, 4498.775061246924, 4561.338605902192, 4755.548222918817, 4759.6668233223345, 8345.332373410112, 9928.0, 9979.550920208609, 9997.000299969945, 10013.849792253122, 5331.280277162611, 5333.759980800874, 5336.306280183629, 5339.893202135935, 5565.749541270652, 4391.758453285036, 4562.8631141065825, 4592.540745925381, 4813.326134341195, 4901.754912254444, 3399.1672040349777, 3884.708646851441, 3887.280853042196, 3973.403989401555, 4023.9195216095513, 1278.8017857232144, 1290.6966862787237, 1296.6758310422395, 1299.0711641176604, 1302.5701518498872, 912.3659056955485, 918.4168053286078, 935.3078721745859, 944.990550094504, 957.0190554681507, 5437.429069947678, 5909.054551271776, 5965.612235204784, 6163.9383606163365, 6181.598196117238, 415.3520508007518, 426.19236546743747, 428.2163640537808, 430.95474975127786, 435.3991491741377, 3522.260325331709, 3916.5691773904846, 3964.742291751027, 4074.2258970795456, 4083.571225021389, 10355.378677279386, 10772.245942784086, 10809.297395669413, 10809.513571889283, 10829.566817327219, 10728.731781705364, 10730.463476826128, 10756.354618722746, 10779.730506737395, 10781.02970732622, 6979.8604027919155, 7965.203479652105, 8167.0, 8249.79375515605, 8262.545559994258, 5879.20630714848, 5879.617824841543, 5902.704864756831, 5933.169356290036, 5976.252968378953, 9725.805483890425, 9797.216222702024, 9860.211183105192, 9901.603935842486, 9940.005999400002, 9929.3545919515, 10123.088921997056, 10150.54322555472, 10168.796624067625, 10244.692659220234, 6777.627230502172, 6888.0, 7165.319294666998, 7575.886361704689, 7608.619569021637, 8322.70870519529, 8379.162083791573, 8470.0, 8499.74500764978, 8512.319014478702, 7401.555906645618, 7501.28737769911, 7525.24747525265, 7561.886571701539, 7624.123225828879, 7248.710051597877, 7294.927050729425, 7340.339369456775, 7430.294122058396, 7489.363404110712, 6863.9656801717665, 7010.50926435165, 7019.508634395544, 7023.262557431601, 7030.894536581853, 4493.393391892184, 4728.983268597268, 4930.235813448921, 5101.846944591668, 5109.284700141909, 5023.723695196799, 5045.678987665715, 5111.0, 5184.0, 5226.503482169188], "value": 4469.825027751898, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [270.6684311718145, 283.4501067928219, 289.71607824332165, 304.8948112901048, 310.4195155060038, 5157.974210128944, 5365.248865158876, 5395.679857661205, 5420.593455490839, 5473.91789123163, 5336.733163341837, 5348.518633323, 5371.704556249405, 5447.700376479302, 5463.863403414906, 3883.106885416349, 3903.0, 4052.533958594755, 4084.530279017906, 4172.913543228386, 3424.6746559076973, 4043.231785960673, 4083.2241874043984, 4084.8774536763945, 4116.464859568252, 3383.2556837495713, 4097.078157414588, 4111.958880411203, 4122.732022418533, 4172.123853990665, 3735.5704094028993, 4033.233685599756, 4063.878083657495, 4124.938125928114, 4142.399352093945, 3476.6870981611523, 3484.059303987909, 3500.2824420993634, 3504.5794504659348, 3531.0579181450357, 5166.276721259027, 5264.31563896691, 5266.604349847294, 5303.416624171338, 5308.787648494055, 3708.1471261609713, 3895.5130608673917, 3987.6442009716834, 4269.530351661315, 4660.650451216173, 4830.6135509159185, 4838.588719958808, 4908.680361282085, 5323.627346085776, 5711.457411545896, 3241.7082462578246, 3344.5317655528, 3675.467057276707, 3813.31360355136, 3824.5028146341074, 5083.262926875619, 5600.915986260209, 5902.144189092601, 5941.702914854283, 6010.609310394854, 3276.4921437177277, 3827.158025234444, 3837.865674701372, 3910.843566257318, 3917.9608203917596, 3726.8509259629855, 3923.372260438316, 3964.841406343714, 3971.4439978403047, 3986.3023970804993, 3862.6523612874416, 4601.44782626087, 4607.723536587815, 4632.008236458355, 4663.51033141522, 4819.783109760069, 5262.342207224097, 5322.494363035506, 5402.540784033401, 5778.024317476419, 1341.610932829488, 1362.9727405451836, 1369.5480491437802, 1372.61566761307, 1383.9515616953358, 691.5746815708303, 727.8617062758059, 772.9227077292338, 812.626191951697, 834.9290310323569, 4323.221820072355, 4435.6229720473475, 4844.442889067803, 5100.668456550312, 5246.685198888079, 5184.507471790174, 5362.168863826113, 5724.055530837365, 5937.061154728437, 5999.430054144849, 4420.8673739787855, 4577.656675749332, 4764.78558464863, 4834.419869615598, 5088.269988204029, 2846.5445528715304, 2869.9282517937218, 2941.985015169763, 3138.168385377877, 3140.5917230759856, 5185.403678576937, 5434.619576629677, 5963.910541341883, 6069.6935164638035, 6101.969490152521, 5516.503514683698, 5684.886302274014, 5730.0, 5981.37195594465, 6013.45878870895, 6084.201267416949, 6112.982715703807, 6128.111423843607, 6130.099669102592, 6221.666874921904, 5044.722540260321, 5081.517255860687, 5648.068068768607, 5871.354151043467, 5890.823275301748, 5816.534677225896, 5829.0, 5830.387809279964, 5857.326407463196, 5871.530277577698, 6067.8435271838935, 6089.908651370144, 6133.26400831903, 6169.9947517058035, 6192.752289908533, 3611.4041183204477, 3861.362875125573, 3861.5366156061455, 3862.5751167371004, 3875.6511913927884, 5688.23664663939, 5845.766169353178, 5853.86818052357, 5862.983856058613, 5880.676562789087, 5813.924423981577, 5828.475437210502, 5853.414658534112, 5870.972579798604, 5922.5261979040715, 5514.476124768141, 5732.942670573408, 5741.45456181662, 5741.655500669973, 5798.956187886052, 5655.7454914528125, 5665.0, 5671.015144699322, 5703.686297253691, 5727.942720572741, 4770.809167633256, 4812.735299558558, 5446.482584154499, 5636.844446888296, 5718.285214348207, 4653.673702994631, 5040.0, 5607.607467477401, 5616.522595579421, 5640.943590564206], "value": 4098.835341617605, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [690.2890023276027, 713.9607321597313, 757.6174032113785, 761.977140685779, 796.6295672512282, 14995.550133495999, 16113.758293625575, 16128.112953787566, 16174.676506469877, 16196.352145914165, 14531.92734036331, 14641.0, 14661.706765864676, 14674.706505869875, 14689.559313220621, 661.2032500836481, 688.2738710660251, 814.7963009247688, 819.8319344534386, 848.9915100849005, 744.7989042958397, 833.9791505212357, 840.8402403543307, 854.5513605357206, 856.220839036477, 742.9219931907153, 824.1346586084617, 907.2515174980667, 908.582052255963, 910.0171814440422, 892.0989800301677, 893.4907102951321, 901.3870568013748, 902.4900930973979, 906.5557876640464, 639.6545865232769, 640.3948268885905, 644.0, 660.2341284110408, 669.2838662630987, 15290.617734556614, 15294.541163765105, 15407.30667119971, 15575.688486230212, 15589.766153507706, 14050.43798248069, 14476.565703028926, 14663.533646635358, 14681.706365872622, 14720.411183552646, 11760.941195294054, 11894.702632434171, 11999.580014699444, 12045.39773011346, 12048.879511204863, 3743.457198706172, 3790.1851102013197, 3821.541415030214, 3854.9807250963845, 3932.0956180078742, 85481.0, 104560.47719761352, 104694.47652761688, 119784.0, 120391.0, 10900.563977440814, 11001.284916480403, 11070.557177712963, 11108.666739997812, 11167.441627918572, 21314.254001110196, 21321.933903304773, 21334.466638333855, 21351.572968540542, 21358.893205533874, 7716.7684969451, 7960.442769006114, 7972.162922893129, 7973.401994850409, 8153.184681531918, 4314.827406903689, 4443.866683999485, 4496.460424748986, 4536.909261814745, 4538.364628951949, 3182.236263296792, 3559.4482855157494, 3664.13892735207, 3694.8522059117336, 3705.6664900159117, 660.247318057415, 667.1094089390621, 673.5554534007531, 685.85939882324, 689.7827184436886, 542.3898114621052, 552.8645481856971, 632.3992207402894, 650.8568115014689, 679.4632240530025, 8656.307495400353, 9294.256459483226, 9313.44119352841, 9319.673811416567, 9708.174805141502, 542.3634530473167, 604.6704546022428, 615.8460384903774, 660.9933900660932, 671.7380221713536, 4403.8238470461465, 4414.646828253796, 4688.296755486705, 4697.906041879143, 4741.2888066789565, 88967.55516222506, 91062.0, 127708.36145819398, 165209.0, 166336.16831915762, 141021.29489352694, 142013.0, 145234.0, 145242.0, 147796.0, 122142.0, 123272.0, 124068.75931240752, 124856.37571812083, 124889.37555312166, 7178.461615378972, 8907.955460222658, 8946.955265223633, 8964.462132272085, 9040.18581882659, 47576.0, 67302.0, 68346.97479537911, 68820.6558967202, 69951.65024174846, 78874.6056269715, 79899.0, 83269.0, 92006.07993919976, 95450.0, 12953.0, 13184.143030702973, 13435.328233588478, 13722.0, 13786.241756703477, 14499.0, 14778.743806776114, 14918.32867520986, 15340.386384544494, 15368.154751488328, 11570.710732231424, 12028.0, 12339.444724987217, 13858.307084645929, 14328.856711432752, 13364.331783410596, 13631.38658760378, 13765.242911639956, 14078.944079193896, 14258.42966281337, 11310.434478276218, 13062.725871137558, 13573.253471058792, 13711.862881371457, 13712.971527135704, 10806.243562950518, 11122.132180088534, 11175.944120279346, 11249.437528123397, 11341.659750207507, 9535.713928582154, 9771.706848794547, 10145.746356341151, 11399.658010259705, 11569.247998880046], "value": 7776.859463720242, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1575.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 120.0, 141.0, 171.0, 117.0, 129.0, 120.0, 118.0, 118.0, 119.0], "value": 126.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 135.0, 113.0, 152.0, 116.0, 116.0, 129.0, 115.0, 143.0, 116.0], "value": 128.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 201.0, 196.0, 238.0, 275.0, 236.0, 193.0, 197.0, 195.0, 194.0], "value": 211.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 60.0, 57.0, 83.0, 61.0, 59.0, 61.0, 71.0, 105.0, 83.0], "value": 72.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 73.0, 89.0, 74.0, 68.0, 71.0, 89.0, 68.0, 71.0, 87.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 90.0, 93.0, 90.0, 94.0, 88.0, 94.0, 76.0, 135.0, 132.0], "value": 98.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 130.0, 119.0, 98.0, 98.0, 98.0, 98.0, 96.0, 89.0, 99.0], "value": 102.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 96.0, 122.0, 129.0, 138.0, 131.0, 99.0, 87.0, 77.0, 125.0], "value": 114.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 60.0, 56.0, 57.0, 64.0, 88.0, 49.0, 54.0, 56.0, 88.0], "value": 62.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 77.0, 80.0, 84.0, 59.0, 89.0, 102.0, 105.0, 88.0], "value": 82.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 88.0, 92.0, 88.0, 86.0, 91.0, 88.0, 90.0, 93.0], "value": 88.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 170.0, 141.0, 142.0, 123.0, 158.0, 167.0, 167.0, 199.0, 171.0], "value": 158.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 175.0, 177.0, 173.0, 175.0, 179.0, 223.0, 174.0, 173.0, 172.0], "value": 179.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 71.0, 69.0, 71.0, 71.0, 70.0, 83.0, 75.0, 68.0], "value": 71.5, "unit": "score"}]}]} 05:25:19 INFO - Return code: 0 05:25:19 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:25:19 ERROR - # TBPL SUCCESS # 05:25:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:25:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:25:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:25:19 INFO - 2016-10-14 05:25:19.520 screenresolution[1950:18470] starting screenresolution argv=screenresolution get 05:25:19 INFO - 2016-10-14 05:25:19.522 screenresolution[1950:18470] Display 0: 1600x1200x32@60 05:25:19 INFO - 2016-10-14 05:25:19.528 screenresolution[1951:18473] starting screenresolution argv=screenresolution list 05:25:19 INFO - Available Modes on Display 0 05:25:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:25:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:25:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:25:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:25:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:25:19 INFO - Intel Iris: 05:25:19 INFO - Chipset Model: Intel Iris 05:25:19 INFO - Type: GPU 05:25:19 INFO - Bus: Built-In 05:25:19 INFO - VRAM (Dynamic, Max): 1536 MB 05:25:19 INFO - Vendor: Intel (0x8086) 05:25:19 INFO - Device ID: 0x0a2e 05:25:19 INFO - Revision ID: 0x0009 05:25:19 INFO - Displays: 05:25:19 INFO - Display: 05:25:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:25:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:25:19 INFO - Display Serial Number: 1600x1200 60 05:25:19 INFO - Main Display: Yes 05:25:19 INFO - Mirror: Off 05:25:19 INFO - Online: Yes 05:25:19 INFO - Rotation: Supported 05:25:19 INFO - Return code: 0 05:25:19 INFO - Running post-action listener: _resource_record_post_action 05:25:19 INFO - [mozharness: 2016-10-14 12:25:19.769392Z] Finished run-tests step (success) 05:25:19 INFO - Running post-run listener: _resource_record_post_run 05:25:19 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 3072512; Write bytes: 707709440; Read time: 37; Write time: 1982 05:25:19 INFO - TinderboxPrint: CPU usage
54.5% 05:25:19 INFO - TinderboxPrint: I/O read bytes / time
3,072,512 / 37 05:25:19 INFO - TinderboxPrint: I/O write bytes / time
707,709,440 / 1,982 05:25:19 INFO - TinderboxPrint: CPU idle
2,205.2 (45.5%) 05:25:19 INFO - TinderboxPrint: CPU system
985.1 (20.3%) 05:25:19 INFO - TinderboxPrint: CPU user
1,655.4 (34.2%) 05:25:19 INFO - TinderboxPrint: Swap in / out
409,350,144 / 0 05:25:19 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 383891968; Write bytes: 378480640; Read time: 29097; Write time: 775 05:25:19 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 3072512; Write bytes: 298562048; Read time: 37; Write time: 1132 05:25:20 INFO - Running post-run listener: _upload_blobber_files 05:25:20 INFO - Blob upload gear active. 05:25:20 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:25:20 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 05:25:20 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'] 05:25:20 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 05:25:20 INFO - (blobuploader) - INFO - Open directory for files ... 05:25:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:25:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:25:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:21 INFO - (blobuploader) - INFO - Done attempting. 05:25:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:25:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:22 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:25:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:22 INFO - (blobuploader) - INFO - Done attempting. 05:25:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:25:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:25:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:25:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:25:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:25:24 INFO - (blobuploader) - INFO - Done attempting. 05:25:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:25:24 INFO - Return code: 0 05:25:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:25:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:25:24 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b23dd9d150eb1f4bd3c72e9cce17502af59bf14716aba43efd4e607a8de72afecbccb403a09ad936e515f6e93c8049c3d7c24341601736cbc27c59e23ebf9771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4304b35df77c526d968acaf18509591e035f94c315c0860ba8b1f7bf28961c6c4755e11f8ba4521d2074c972c08e00dee3936b2dac8d6cc52b57f5744c52512e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38aa7db79cc9f696d1be7dafcff02a7859c0ed31ecc80d187b406c251ff68638b3307dd345861937b3a26fc3c6af4ca13570e06c053d2ab5a5a3c716afd499a8"} 05:25:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:25:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:25:24 INFO - Contents: 05:25:24 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b23dd9d150eb1f4bd3c72e9cce17502af59bf14716aba43efd4e607a8de72afecbccb403a09ad936e515f6e93c8049c3d7c24341601736cbc27c59e23ebf9771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4304b35df77c526d968acaf18509591e035f94c315c0860ba8b1f7bf28961c6c4755e11f8ba4521d2074c972c08e00dee3936b2dac8d6cc52b57f5744c52512e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38aa7db79cc9f696d1be7dafcff02a7859c0ed31ecc80d187b406c251ff68638b3307dd345861937b3a26fc3c6af4ca13570e06c053d2ab5a5a3c716afd499a8"} 05:25:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:25:24 INFO - Copying logs to upload dir... 05:25:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:25:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1270.448508 ========= master_lag: 0.27 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 10 secs) (at 2016-10-14 05:25:24.503523) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 05:25:24.507982) ========= 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.gy1tiX0DLV/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.1DoAOLezI2/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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/b23dd9d150eb1f4bd3c72e9cce17502af59bf14716aba43efd4e607a8de72afecbccb403a09ad936e515f6e93c8049c3d7c24341601736cbc27c59e23ebf9771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4304b35df77c526d968acaf18509591e035f94c315c0860ba8b1f7bf28961c6c4755e11f8ba4521d2074c972c08e00dee3936b2dac8d6cc52b57f5744c52512e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38aa7db79cc9f696d1be7dafcff02a7859c0ed31ecc80d187b406c251ff68638b3307dd345861937b3a26fc3c6af4ca13570e06c053d2ab5a5a3c716afd499a8"} build_url:https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011314 build_url: 'https://queue.taskcluster.net/v1/task/YNIUD2b3SZuXi0DG49uZnw/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/b23dd9d150eb1f4bd3c72e9cce17502af59bf14716aba43efd4e607a8de72afecbccb403a09ad936e515f6e93c8049c3d7c24341601736cbc27c59e23ebf9771", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4304b35df77c526d968acaf18509591e035f94c315c0860ba8b1f7bf28961c6c4755e11f8ba4521d2074c972c08e00dee3936b2dac8d6cc52b57f5744c52512e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/38aa7db79cc9f696d1be7dafcff02a7859c0ed31ecc80d187b406c251ff68638b3307dd345861937b3a26fc3c6af4ca13570e06c053d2ab5a5a3c716afd499a8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-14 05:25:24.546344) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:25:24.546694) ========= 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.gy1tiX0DLV/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.1DoAOLezI2/Listeners TMPDIR=/var/folders/cq/fm9x62sj2nq5lg90gjyc1tb000000w/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.005355 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-14 05:25:24.598913) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 05:25:24.599191) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-14 05:25:24.601473) ========= ========= Total master_lag: 0.42 =========