builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0234 starttime: 1476895212.02 results: success (0) buildid: 20161019072622 builduid: 5c06f5b9caa841e68cf6fde3192ef250 revision: 0342df5d06e1f718155d6d754c25aae5f8676764 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.023735) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.024215) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.024504) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.053832) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.054215) ========= 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.mjjDVVA2gH/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.YSLecAHNM1/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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-19 09:40:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 18.7M=0.001s 2016-10-19 09:40:12 (18.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.085897 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.165509) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.165886) ========= 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.mjjDVVA2gH/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.YSLecAHNM1/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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.023504 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:12.214447) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 09:40:12.214917) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 0342df5d06e1f718155d6d754c25aae5f8676764 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 0342df5d06e1f718155d6d754c25aae5f8676764 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mjjDVVA2gH/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.YSLecAHNM1/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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-19 09:40:12,300 truncating revision to first 12 chars 2016-10-19 09:40:12,301 Setting DEBUG logging. 2016-10-19 09:40:12,301 attempt 1/10 2016-10-19 09:40:12,301 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/0342df5d06e1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 09:40:13,678 unpacking tar archive at: fx-team-0342df5d06e1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.625758 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-19 09:40:13.865678) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:13.865996) ========= script_repo_revision: 0342df5d06e1f718155d6d754c25aae5f8676764 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:13.866413) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:13.866693) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 09:40:13.884900) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-19 09:40:13.885308) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 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', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mjjDVVA2gH/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.YSLecAHNM1/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:40:14 INFO - MultiFileLogger online at 20161019 09:40:14 in /builds/slave/test 09:40:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:40:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:40:14 INFO - {'append_to_log': False, 09:40:14 INFO - 'base_work_dir': '/builds/slave/test', 09:40:14 INFO - 'blob_upload_branch': 'Fx-Team', 09:40:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:40:14 INFO - 'branch': 'Fx-Team', 09:40:14 INFO - 'buildbot_json_path': 'buildprops.json', 09:40:14 INFO - 'config_files': ('talos/mac_config.py',), 09:40:14 INFO - 'default_actions': ('clobber', 09:40:14 INFO - 'read-buildbot-config', 09:40:14 INFO - 'download-and-extract', 09:40:14 INFO - 'populate-webroot', 09:40:14 INFO - 'create-virtualenv', 09:40:14 INFO - 'install', 09:40:14 INFO - 'run-tests'), 09:40:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:40:14 INFO - 'download_minidump_stackwalk': True, 09:40:14 INFO - 'download_symbols': 'ondemand', 09:40:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:40:14 INFO - 'tooltool.py': '/tools/tooltool.py', 09:40:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:40:14 INFO - '/tools/misc-python/virtualenv.py')}, 09:40:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:40:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:40:14 INFO - 'installer_path': 'installer.exe', 09:40:14 INFO - 'log_level': 'info', 09:40:14 INFO - 'log_name': 'talos', 09:40:14 INFO - 'log_to_console': True, 09:40:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:40:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:40:14 INFO - 'opt_config_files': (), 09:40:14 INFO - 'pip_index': False, 09:40:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:40:14 INFO - 'cmd': ('bash', 09:40:14 INFO - '-c', 09:40:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:40:14 INFO - 'enabled': True, 09:40:14 INFO - 'halt_on_failure': False, 09:40:14 INFO - 'name': 'check_screen_resolution'},), 09:40:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:40:14 INFO - 'cmd': ('bash', 09:40:14 INFO - '-c', 09:40:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:40:14 INFO - 'enabled': True, 09:40:14 INFO - 'halt_on_failure': False, 09:40:14 INFO - 'name': 'check_screen_resolution'},), 09:40:14 INFO - 'run_cmd_checks_enabled': True, 09:40:14 INFO - 'sps_profile': False, 09:40:14 INFO - 'sps_profile_interval': 0, 09:40:14 INFO - 'suite': 'dromaeojs-e10s', 09:40:14 INFO - 'system_bits': '32', 09:40:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:40:14 INFO - 'title': 't-yosemite-r7-0234', 09:40:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:40:14 INFO - 'use_talos_json': True, 09:40:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:40:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:40:14 INFO - 'work_dir': 'build'} 09:40:14 INFO - [mozharness: 2016-10-19 16:40:14.088275Z] Running clobber step. 09:40:14 INFO - Running pre-action listener: _resource_record_pre_action 09:40:14 INFO - Running main action method: clobber 09:40:14 INFO - rmtree: /builds/slave/test/build 09:40:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:40:16 INFO - Running post-action listener: _resource_record_post_action 09:40:16 INFO - [mozharness: 2016-10-19 16:40:16.117487Z] Finished clobber step (success) 09:40:16 INFO - [mozharness: 2016-10-19 16:40:16.117611Z] Running read-buildbot-config step. 09:40:16 INFO - Running pre-action listener: _resource_record_pre_action 09:40:16 INFO - Running main action method: read_buildbot_config 09:40:16 INFO - Using buildbot properties: 09:40:16 INFO - { 09:40:16 INFO - "project": "", 09:40:16 INFO - "product": "firefox", 09:40:16 INFO - "script_repo_revision": "production", 09:40:16 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:40:16 INFO - "repository": "", 09:40:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:40:16 INFO - "buildid": "20161019072622", 09:40:16 INFO - "pgo_build": "False", 09:40:16 INFO - "basedir": "/builds/slave/test", 09:40:16 INFO - "buildnumber": 797, 09:40:16 INFO - "slavename": "t-yosemite-r7-0234", 09:40:16 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 09:40:16 INFO - "platform": "yosemite_r7", 09:40:16 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 09:40:16 INFO - "branch": "fx-team", 09:40:16 INFO - "repo_path": "integration/fx-team", 09:40:16 INFO - "revision": "0342df5d06e1f718155d6d754c25aae5f8676764", 09:40:16 INFO - "stage_platform": "macosx64", 09:40:16 INFO - "builduid": "5c06f5b9caa841e68cf6fde3192ef250", 09:40:16 INFO - "slavebuilddir": "test" 09:40:16 INFO - } 09:40:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:40:16 INFO - Running post-action listener: _resource_record_post_action 09:40:16 INFO - [mozharness: 2016-10-19 16:40:16.120863Z] Finished read-buildbot-config step (success) 09:40:16 INFO - [mozharness: 2016-10-19 16:40:16.120995Z] Running download-and-extract step. 09:40:16 INFO - Running pre-action listener: _resource_record_pre_action 09:40:16 INFO - Running main action method: download_and_extract 09:40:16 INFO - mkdir: /builds/slave/test/build/tests 09:40:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:16 INFO - trying https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:40:16 INFO - Downloading https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/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 09:40:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/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 09:40:16 INFO - Downloaded 1199 bytes. 09:40:16 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:40:16 INFO - Using the following test package requirements: 09:40:16 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:40:16 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:40:16 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:40:16 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'jsshell-mac.zip'], 09:40:16 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:40:16 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:40:16 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:40:16 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:40:16 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:40:16 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:40:16 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:40:16 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:40:16 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:40:16 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:40:16 INFO - Fetch https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:40:17 INFO - Http code: 200 09:40:17 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 09:40:17 INFO - x-amz-cf-id: VqswPb5jAGGMgxpn7FcQHF41gz7VM4VHtj2A-VzLcUwY4raqesa9bA== 09:40:17 INFO - x-amz-version-id: S2.BodBSG8jnr35kfA69VeCBtOGazDvf 09:40:17 INFO - Expected file size: 16499905 09:40:17 INFO - Obtained file size: 16499905 09:40: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 09:40:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:40:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:40:24 INFO - Fetch https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:40:24 INFO - Http code: 200 09:40:24 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 09:40:24 INFO - x-amz-cf-id: FEc3CuabMFqohfQydh8NfgvTRVKBgiEkNbB1uMVzrkjO06w7LGO8IQ== 09:40:24 INFO - x-amz-version-id: S2.BodBSG8jnr35kfA69VeCBtOGazDvf 09:40:25 INFO - Expected file size: 16499905 09:40:25 INFO - Obtained file size: 16499905 09:40:31 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:40:31 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:40:31 INFO - Fetch https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:40:32 INFO - Http code: 200 09:40:32 INFO - via: 1.1 0302e8c462515ae281b72d9c815a5698.cloudfront.net (CloudFront) 09:40:32 INFO - x-amz-cf-id: YXLljqOZIcfagX2qCl52eNMJJAcETgkXMjRkLLMVuAijF7mS34inVw== 09:40:32 INFO - x-amz-version-id: 1ffGc0zk_9JmVLI9EI3SGarMI9pgDQOQ 09:40:32 INFO - Expected file size: 14409028 09:40:32 INFO - Obtained file size: 14409028 09:40: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')]} 09:40:32 INFO - trying https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:40:32 INFO - Downloading https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:40:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/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 09:40:35 INFO - Downloaded 111641179 bytes. 09:40:35 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:40:35 INFO - mkdir: /builds/slave/test/properties 09:40:35 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:40:35 INFO - Writing to file /builds/slave/test/properties/build_url 09:40:35 INFO - Contents: 09:40:35 INFO - build_url:https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:40:36 INFO - Running post-action listener: _resource_record_post_action 09:40:36 INFO - Running post-action listener: set_extra_try_arguments 09:40:36 INFO - [mozharness: 2016-10-19 16:40:36.141189Z] Finished download-and-extract step (success) 09:40:36 INFO - [mozharness: 2016-10-19 16:40:36.141340Z] Running populate-webroot step. 09:40:36 INFO - Running pre-action listener: _resource_record_pre_action 09:40:36 INFO - Running main action method: populate_webroot 09:40:36 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:40:36 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:40:36 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:40:36 INFO - '--tppagecycles', 09:40:36 INFO - '7'], 09:40:36 INFO - 'tests': ['tsvgm']}}, 09:40:36 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:40:36 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:40:36 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:40:36 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:40:36 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:36 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:40:36 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['damp', 'tps']}, 09:40:36 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'tests': ['damp', 'tps']}, 09:40:36 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['dromaeo_dom']}, 09:40:36 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:40:36 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['basic_compositor_video']}, 09:40:36 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:40:36 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['a11yr', 09:40:36 INFO - 'ts_paint', 09:40:36 INFO - 'tpaint', 09:40:36 INFO - 'sessionrestore', 09:40:36 INFO - 'sessionrestore_no_auto_restore', 09:40:36 INFO - 'tabpaint']}, 09:40:36 INFO - 'other-e10s': {'tests': ['a11yr', 09:40:36 INFO - 'ts_paint', 09:40:36 INFO - 'tpaint', 09:40:36 INFO - 'sessionrestore', 09:40:36 INFO - 'sessionrestore_no_auto_restore', 09:40:36 INFO - 'tabpaint']}, 09:40:36 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:40:36 INFO - 'ts_paint', 09:40:36 INFO - 'tpaint', 09:40:36 INFO - 'sessionrestore', 09:40:36 INFO - 'sessionrestore_no_auto_restore', 09:40:36 INFO - 'tabpaint']}, 09:40:36 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:40:36 INFO - 'ts_paint', 09:40:36 INFO - 'tpaint', 09:40:36 INFO - 'sessionrestore', 09:40:36 INFO - 'sessionrestore_no_auto_restore', 09:40:36 INFO - 'tabpaint']}, 09:40:36 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['a11yr', 09:40:36 INFO - 'ts_paint', 09:40:36 INFO - 'tpaint', 09:40:36 INFO - 'sessionrestore', 09:40:36 INFO - 'sessionrestore_no_auto_restore', 09:40:36 INFO - 'tabpaint']}, 09:40:36 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['a11yr', 09:40:36 INFO - 'ts_paint', 09:40:36 INFO - 'tpaint', 09:40:36 INFO - 'sessionrestore', 09:40:36 INFO - 'sessionrestore_no_auto_restore', 09:40:36 INFO - 'tabpaint']}, 09:40:36 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['tsvgx', 09:40:36 INFO - 'tsvgr_opacity', 09:40:36 INFO - 'tart', 09:40:36 INFO - 'tscrollx', 09:40:36 INFO - 'cart']}, 09:40:36 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:40:36 INFO - 'tsvgr_opacity', 09:40:36 INFO - 'tart', 09:40:36 INFO - 'tscrollx', 09:40:36 INFO - 'cart']}, 09:40:36 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'talos_options': ['--disable-e10s'], 09:40:36 INFO - 'tests': ['tp5o']}, 09:40:36 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'tests': ['tp5o']}, 09:40:36 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'talos_options': ['--disable-e10s', 09:40:36 INFO - '--xperf_path', 09:40:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:36 INFO - 'tests': ['tp5n']}, 09:40:36 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:40:36 INFO - 'talos_options': ['--xperf_path', 09:40:36 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:40:36 INFO - 'tests': ['tp5n']}}, 09:40:36 INFO - 'talos.zip': {'path': '', 09:40:36 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:40:36 INFO - Running post-action listener: _resource_record_post_action 09:40:36 INFO - [mozharness: 2016-10-19 16:40:36.159773Z] Finished populate-webroot step (success) 09:40:36 INFO - [mozharness: 2016-10-19 16:40:36.159876Z] Running create-virtualenv step. 09:40:36 INFO - Running pre-action listener: _resource_record_pre_action 09:40:36 INFO - Running main action method: create_virtualenv 09:40:36 INFO - Creating virtualenv /builds/slave/test/build/venv 09:40: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 09:40:36 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:40:36 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:40:36 INFO - Using real prefix '/tools/python27' 09:40:36 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:40:37 INFO - Installing distribute.............................................................................................................................................................................................done. 09:40:39 INFO - Installing pip.................done. 09:40:39 INFO - Return code: 0 09:40:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:40:40 INFO - Reading from file tmpfile_stdout 09:40:40 INFO - Output received: 09:40: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) 09:40:40 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:40: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')]} 09:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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')]} 09:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:40 INFO - 'HOME': '/Users/cltbld', 09:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:40 INFO - 'LOGNAME': 'cltbld', 09:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:40 INFO - 'MOZ_NO_REMOTE': '1', 09:40:40 INFO - 'NO_EM_RESTART': '1', 09:40:40 INFO - 'PAGER': '/bin/cat', 09:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:40 INFO - 'PWD': '/builds/slave/test', 09:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:40 INFO - 'SHELL': '/bin/bash', 09:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:40 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:40 INFO - 'USER': 'cltbld', 09:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:40 INFO - 'XPC_FLAGS': '0x0', 09:40:40 INFO - 'XPC_SERVICE_NAME': '0', 09:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40: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 09:40:40 INFO - Cleaning up... 09:40:40 INFO - Return code: 0 09:40:40 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:40: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')]} 09:40:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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')]} 09:40:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:40 INFO - 'HOME': '/Users/cltbld', 09:40:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:40 INFO - 'LOGNAME': 'cltbld', 09:40:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:40 INFO - 'MOZ_NO_REMOTE': '1', 09:40:40 INFO - 'NO_EM_RESTART': '1', 09:40:40 INFO - 'PAGER': '/bin/cat', 09:40:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:40 INFO - 'PWD': '/builds/slave/test', 09:40:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:40 INFO - 'SHELL': '/bin/bash', 09:40:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:40 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:40 INFO - 'USER': 'cltbld', 09:40:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:40 INFO - 'XPC_FLAGS': '0x0', 09:40:40 INFO - 'XPC_SERVICE_NAME': '0', 09:40:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:40 INFO - Downloading/unpacking psutil>=3.1.1 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:40:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:44 INFO - Installing collected packages: psutil 09:40:44 INFO - Running setup.py install for psutil 09:40:44 INFO - building 'psutil._psutil_osx' extension 09:40: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 09:40: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 09:40: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 09:40: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 09:40:44 INFO - building 'psutil._psutil_posix' extension 09:40: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 09:40:45 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:40:45 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:40:45 INFO - ^ 09:40:45 INFO - 1 warning generated. 09:40: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 09:40:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:40:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:40:45 INFO - Successfully installed psutil 09:40:45 INFO - Cleaning up... 09:40:45 INFO - Return code: 0 09:40:45 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:40: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')]} 09:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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')]} 09:40:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:45 INFO - 'HOME': '/Users/cltbld', 09:40:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:45 INFO - 'LOGNAME': 'cltbld', 09:40:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:45 INFO - 'MOZ_NO_REMOTE': '1', 09:40:45 INFO - 'NO_EM_RESTART': '1', 09:40:45 INFO - 'PAGER': '/bin/cat', 09:40:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:45 INFO - 'PWD': '/builds/slave/test', 09:40:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:45 INFO - 'SHELL': '/bin/bash', 09:40:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:45 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:45 INFO - 'USER': 'cltbld', 09:40:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:45 INFO - 'XPC_FLAGS': '0x0', 09:40:45 INFO - 'XPC_SERVICE_NAME': '0', 09:40:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:45 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:45 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:45 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:40:45 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:40:45 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:40:48 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:40:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:40:48 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:40:48 INFO - Installing collected packages: mozsystemmonitor 09:40:48 INFO - Running setup.py install for mozsystemmonitor 09:40:48 INFO - Successfully installed mozsystemmonitor 09:40:48 INFO - Cleaning up... 09:40:48 INFO - Return code: 0 09:40:48 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:48 INFO - 'HOME': '/Users/cltbld', 09:40:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:48 INFO - 'LOGNAME': 'cltbld', 09:40:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:48 INFO - 'MOZ_NO_REMOTE': '1', 09:40:48 INFO - 'NO_EM_RESTART': '1', 09:40:48 INFO - 'PAGER': '/bin/cat', 09:40:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:48 INFO - 'PWD': '/builds/slave/test', 09:40:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:48 INFO - 'SHELL': '/bin/bash', 09:40:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:48 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:48 INFO - 'USER': 'cltbld', 09:40:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:48 INFO - 'XPC_FLAGS': '0x0', 09:40:48 INFO - 'XPC_SERVICE_NAME': '0', 09:40:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:48 INFO - Downloading/unpacking jsonschema==2.5.1 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:51 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:40:51 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:51 INFO - Downloading functools32-3.2.3-2.tar.gz 09:40:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:40:51 INFO - warning: no files found matching '*.txt' 09:40:51 INFO - no previously-included directories found matching 'build' 09:40:51 INFO - no previously-included directories found matching 'dist' 09:40:51 INFO - no previously-included directories found matching '.git*' 09:40:51 INFO - Installing collected packages: jsonschema, functools32 09:40:51 INFO - Running setup.py install for functools32 09:40:51 INFO - warning: no files found matching '*.txt' 09:40:51 INFO - no previously-included directories found matching 'build' 09:40:51 INFO - no previously-included directories found matching 'dist' 09:40:51 INFO - no previously-included directories found matching '.git*' 09:40:51 INFO - Successfully installed jsonschema functools32 09:40:51 INFO - Cleaning up... 09:40:51 INFO - Return code: 0 09:40:51 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:40: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')]} 09:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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')]} 09:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:51 INFO - 'HOME': '/Users/cltbld', 09:40:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:51 INFO - 'LOGNAME': 'cltbld', 09:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:51 INFO - 'MOZ_NO_REMOTE': '1', 09:40:51 INFO - 'NO_EM_RESTART': '1', 09:40:51 INFO - 'PAGER': '/bin/cat', 09:40:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:51 INFO - 'PWD': '/builds/slave/test', 09:40:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:51 INFO - 'SHELL': '/bin/bash', 09:40:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:51 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:51 INFO - 'USER': 'cltbld', 09:40:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:51 INFO - 'XPC_FLAGS': '0x0', 09:40:51 INFO - 'XPC_SERVICE_NAME': '0', 09:40:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:52 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:40:52 INFO - Cleaning up... 09:40:52 INFO - Return code: 0 09:40:52 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:40: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')]} 09:40:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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')]} 09:40:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:52 INFO - 'HOME': '/Users/cltbld', 09:40:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:52 INFO - 'LOGNAME': 'cltbld', 09:40:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:52 INFO - 'MOZ_NO_REMOTE': '1', 09:40:52 INFO - 'NO_EM_RESTART': '1', 09:40:52 INFO - 'PAGER': '/bin/cat', 09:40:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:52 INFO - 'PWD': '/builds/slave/test', 09:40:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:52 INFO - 'SHELL': '/bin/bash', 09:40:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:52 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:52 INFO - 'USER': 'cltbld', 09:40:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:52 INFO - 'XPC_FLAGS': '0x0', 09:40:52 INFO - 'XPC_SERVICE_NAME': '0', 09:40:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:52 INFO - Downloading/unpacking blobuploader==1.2.4 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:54 INFO - Downloading blobuploader-1.2.4.tar.gz 09:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:40:55 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:40:55 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40: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 09:40:56 INFO - Downloading docopt-0.6.1.tar.gz 09:40:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:40:56 INFO - Installing collected packages: blobuploader, requests, docopt 09:40:56 INFO - Running setup.py install for blobuploader 09:40:56 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:40:56 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:40:56 INFO - Running setup.py install for requests 09:40:56 INFO - Running setup.py install for docopt 09:40:57 INFO - Successfully installed blobuploader requests docopt 09:40:57 INFO - Cleaning up... 09:40:57 INFO - Return code: 0 09:40:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:40: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:40: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 09:40: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 09:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:40:57 INFO - 'HOME': '/Users/cltbld', 09:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:40:57 INFO - 'LOGNAME': 'cltbld', 09:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:40:57 INFO - 'MOZ_NO_REMOTE': '1', 09:40:57 INFO - 'NO_EM_RESTART': '1', 09:40:57 INFO - 'PAGER': '/bin/cat', 09:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:40:57 INFO - 'PWD': '/builds/slave/test', 09:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:40:57 INFO - 'SHELL': '/bin/bash', 09:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:40:57 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:40:57 INFO - 'USER': 'cltbld', 09:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:40:57 INFO - 'XPC_FLAGS': '0x0', 09:40:57 INFO - 'XPC_SERVICE_NAME': '0', 09:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:40:57 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-dNTP3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:40:57 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-ItRqIh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:40:57 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-4sJeFY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:40:57 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-EfzvR2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:40:57 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-s6hWKr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:40:57 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-6wmQJD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-YHI4vz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-wCtMHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-OAMyMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-csTP0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-eSDfAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-1MyZwv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-dAfErf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-IgGMKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:40:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:58 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-PjhDKn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:40:59 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-8tbvTE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:40:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:40:59 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-2Psnz7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:40:59 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:40:59 INFO - Running setup.py install for manifestparser 09:40:59 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:40:59 INFO - Running setup.py install for mozcrash 09:40:59 INFO - Running setup.py install for mozdebug 09:40:59 INFO - Running setup.py install for mozdevice 09:40:59 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:40:59 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:40:59 INFO - Running setup.py install for mozfile 09:40:59 INFO - Running setup.py install for mozhttpd 09:41:00 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Running setup.py install for mozinfo 09:41:00 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Running setup.py install for mozInstall 09:41:00 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Running setup.py install for mozleak 09:41:00 INFO - Running setup.py install for mozlog 09:41:00 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Running setup.py install for moznetwork 09:41:00 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:41:00 INFO - Running setup.py install for mozprocess 09:41:01 INFO - Running setup.py install for mozprofile 09:41:01 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Running setup.py install for mozrunner 09:41:01 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Running setup.py install for mozscreenshot 09:41:01 INFO - Running setup.py install for moztest 09:41:01 INFO - Running setup.py install for mozversion 09:41:01 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:41:01 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:41:01 INFO - Cleaning up... 09:41:01 INFO - Return code: 0 09:41:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41: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')]} 09:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41: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')]} 09:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41: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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41: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 09:41: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 09:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:01 INFO - 'HOME': '/Users/cltbld', 09:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:01 INFO - 'LOGNAME': 'cltbld', 09:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:01 INFO - 'MOZ_NO_REMOTE': '1', 09:41:01 INFO - 'NO_EM_RESTART': '1', 09:41:01 INFO - 'PAGER': '/bin/cat', 09:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:01 INFO - 'PWD': '/builds/slave/test', 09:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:01 INFO - 'SHELL': '/bin/bash', 09:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:41:01 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:01 INFO - 'USER': 'cltbld', 09:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:01 INFO - 'XPC_FLAGS': '0x0', 09:41:01 INFO - 'XPC_SERVICE_NAME': '0', 09:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-K13d0q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-qrEror-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-dfTZUY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-ItI54T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-lH8rAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-DfTpY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-LjOmkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-AXydu2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:41: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)) 09:41:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:41:02 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-amhfwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:41:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-rnOFRe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-KceEzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-QOoJtb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-BmWFxE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-ljNwcz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-RoaDO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-uuwu8z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:41: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)) 09:41:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:41:03 INFO - Running setup.py (path:/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/pip-51Ov2R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:41: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)) 09:41: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)) 09:41: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)) 09:41: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)) 09:41: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)) 09:41: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)) 09:41:03 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:41: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 09:41: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 09:41: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 09:41: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 09:41: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 09:41: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 09:41:06 INFO - Downloading blessings-1.6.tar.gz 09:41:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:41:06 INFO - Installing collected packages: blessings 09:41:06 INFO - Running setup.py install for blessings 09:41:06 INFO - Successfully installed blessings 09:41:06 INFO - Cleaning up... 09:41:06 INFO - Return code: 0 09:41:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:41:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:41:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:41:07 INFO - Reading from file tmpfile_stdout 09:41:07 INFO - Current package versions: 09:41:07 INFO - blessings == 1.6 09:41:07 INFO - blobuploader == 1.2.4 09:41:07 INFO - docopt == 0.6.1 09:41:07 INFO - functools32 == 3.2.3-2 09:41:07 INFO - jsonschema == 2.5.1 09:41:07 INFO - manifestparser == 1.1 09:41:07 INFO - mozInstall == 1.12 09:41:07 INFO - mozcrash == 0.17 09:41:07 INFO - mozdebug == 0.1 09:41:07 INFO - mozdevice == 0.48 09:41:07 INFO - mozfile == 1.2 09:41:07 INFO - mozhttpd == 0.7 09:41:07 INFO - mozinfo == 0.9 09:41:07 INFO - mozleak == 0.1 09:41:07 INFO - mozlog == 3.4 09:41:07 INFO - moznetwork == 0.27 09:41:07 INFO - mozprocess == 0.23 09:41:07 INFO - mozprofile == 0.28 09:41:07 INFO - mozrunner == 6.12 09:41:07 INFO - mozscreenshot == 0.1 09:41:07 INFO - mozsystemmonitor == 0.3 09:41:07 INFO - moztest == 0.7 09:41:07 INFO - mozversion == 1.4 09:41:07 INFO - psutil == 3.1.1 09:41:07 INFO - requests == 1.2.3 09:41:07 INFO - wsgiref == 0.1.2 09:41:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:41: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')]} 09:41:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41: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')]} 09:41:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:08 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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:08 INFO - 'HOME': '/Users/cltbld', 09:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:08 INFO - 'LOGNAME': 'cltbld', 09:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:08 INFO - 'MOZ_NO_REMOTE': '1', 09:41:08 INFO - 'NO_EM_RESTART': '1', 09:41:08 INFO - 'PAGER': '/bin/cat', 09:41:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:08 INFO - 'PWD': '/builds/slave/test', 09:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:08 INFO - 'SHELL': '/bin/bash', 09:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:41:08 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:08 INFO - 'USER': 'cltbld', 09:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:08 INFO - 'XPC_FLAGS': '0x0', 09:41:08 INFO - 'XPC_SERVICE_NAME': '0', 09:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:41:08 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:41:08 INFO - Cleaning up... 09:41:08 INFO - Return code: 0 09:41:08 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:41:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:41:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:41:08 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 0x10cc5e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cbd4570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd34a496360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, '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.mjjDVVA2gH/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.YSLecAHNM1/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:41:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:41:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:41:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:41:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:08 INFO - 'HOME': '/Users/cltbld', 09:41:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:08 INFO - 'LOGNAME': 'cltbld', 09:41:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:08 INFO - 'MOZ_NO_REMOTE': '1', 09:41:08 INFO - 'NO_EM_RESTART': '1', 09:41:08 INFO - 'PAGER': '/bin/cat', 09:41:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:08 INFO - 'PWD': '/builds/slave/test', 09:41:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:08 INFO - 'SHELL': '/bin/bash', 09:41:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:41:08 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:41:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:08 INFO - 'USER': 'cltbld', 09:41:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:08 INFO - 'XPC_FLAGS': '0x0', 09:41:08 INFO - 'XPC_SERVICE_NAME': '0', 09:41:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:41:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:41:09 INFO - Cleaning up... 09:41:09 INFO - Return code: 0 09:41:09 INFO - Running post-action listener: _resource_record_post_action 09:41:09 INFO - Running post-action listener: _start_resource_monitoring 09:41:09 INFO - Starting resource monitoring. 09:41:09 INFO - [mozharness: 2016-10-19 16:41:09.068742Z] Finished create-virtualenv step (success) 09:41:09 INFO - [mozharness: 2016-10-19 16:41:09.069694Z] Running install step. 09:41:09 INFO - Running pre-action listener: _resource_record_pre_action 09:41:09 INFO - Running main action method: install 09:41:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:41:09 INFO - Reading from file tmpfile_stdout 09:41:09 INFO - Detecting whether we're running mozinstall >=1.0... 09:41:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:41:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:41:09 INFO - Reading from file tmpfile_stdout 09:41:09 INFO - Output received: 09:41:09 INFO - Usage: mozinstall [options] installer 09:41:09 INFO - Options: 09:41:09 INFO - -h, --help show this help message and exit 09:41:09 INFO - -d DEST, --destination=DEST 09:41:09 INFO - Directory to install application into. [default: 09:41:09 INFO - "/builds/slave/test"] 09:41:09 INFO - --app=APP Application being installed. [default: firefox] 09:41:09 INFO - mkdir: /builds/slave/test/build/application 09:41:09 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'] 09:41:09 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 09:41:44 INFO - Reading from file tmpfile_stdout 09:41:44 INFO - Output received: 09:41:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:41:44 INFO - Running post-action listener: _resource_record_post_action 09:41:44 INFO - [mozharness: 2016-10-19 16:41:44.102327Z] Finished install step (success) 09:41:44 INFO - [mozharness: 2016-10-19 16:41:44.102470Z] Running run-tests step. 09:41:44 INFO - Running pre-action listener: _resource_record_pre_action 09:41:44 INFO - Running main action method: run_tests 09:41:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:41:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:41:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:41:44 INFO - 2016-10-19 09:41:44.117 screenresolution[1640:7485] starting screenresolution argv=screenresolution get 09:41:44 INFO - 2016-10-19 09:41:44.119 screenresolution[1640:7485] Display 0: 1600x1200x32@60 09:41:44 INFO - 2016-10-19 09:41:44.125 screenresolution[1641:7487] starting screenresolution argv=screenresolution list 09:41:44 INFO - Available Modes on Display 0 09:41:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:41:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:41:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:41:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:41:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:41:44 INFO - Intel Iris: 09:41:44 INFO - Chipset Model: Intel Iris 09:41:44 INFO - Type: GPU 09:41:44 INFO - Bus: Built-In 09:41:44 INFO - VRAM (Dynamic, Max): 1536 MB 09:41:44 INFO - Vendor: Intel (0x8086) 09:41:44 INFO - Device ID: 0x0a2e 09:41:44 INFO - Revision ID: 0x0009 09:41:44 INFO - Displays: 09:41:44 INFO - Display: 09:41:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:41:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:41:44 INFO - Display Serial Number: 1600x1200 60 09:41:44 INFO - Main Display: Yes 09:41:44 INFO - Mirror: Off 09:41:44 INFO - Online: Yes 09:41:44 INFO - Rotation: Supported 09:41:44 INFO - Return code: 0 09:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:41:44 INFO - Python 2.7.3 09:41:44 INFO - Return code: 0 09:41:44 INFO - grabbing minidump binary from tooltool 09:41:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:41:44 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 0x10ca72d30>, '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 0x10c898db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ca6ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:41:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:41:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:41:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:41:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:41:44 INFO - Return code: 0 09:41:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:41:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:41:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:41:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:41:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:41:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0234', '--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 09:41:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0234 --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 09:41:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mjjDVVA2gH/Render', 09:41:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:41:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:41:44 INFO - 'HOME': '/Users/cltbld', 09:41:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:41:44 INFO - 'LOGNAME': 'cltbld', 09:41:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:41:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:41:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:41:44 INFO - 'MOZ_NO_REMOTE': '1', 09:41:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:41:44 INFO - 'NO_EM_RESTART': '1', 09:41:44 INFO - 'PAGER': '/bin/cat', 09:41:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:41:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:41:44 INFO - 'PWD': '/builds/slave/test', 09:41:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:41:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:41:44 INFO - 'SHELL': '/bin/bash', 09:41:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.YSLecAHNM1/Listeners', 09:41:44 INFO - 'TMPDIR': '/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/', 09:41:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:41:44 INFO - 'USER': 'cltbld', 09:41:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:41:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:41:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:41:44 INFO - 'XPC_FLAGS': '0x0', 09:41:44 INFO - 'XPC_SERVICE_NAME': '0', 09:41:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:41:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0234', '--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 09:41:44 INFO - mozversion application_buildid: 20161019072622 09:41:44 INFO - mozversion application_changeset: 0342df5d06e1f718155d6d754c25aae5f8676764 09:41:44 INFO - mozversion application_display_name: Nightly 09:41:44 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:41:44 INFO - mozversion application_name: Firefox 09:41:44 INFO - mozversion application_remotingname: firefox 09:41:44 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:41:44 INFO - mozversion application_vendor: Mozilla 09:41:44 INFO - mozversion application_version: 52.0a1 09:41:44 INFO - mozversion platform_buildid: 20161019072622 09:41:44 INFO - mozversion platform_changeset: 0342df5d06e1f718155d6d754c25aae5f8676764 09:41:44 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:41:44 INFO - mozversion platform_version: 52.0a1 09:41:44 INFO - using testdate: 1476895304 09:41:44 INFO - actual date: 1476895304 09:41:44 INFO - starting webserver on 'localhost:49267' 09:41:44 INFO - SUITE-START | Running 2 tests 09:41:44 INFO - TEST-START | dromaeo_css 09:41:44 INFO - operating with platform_type : mac_ 09:41:44 INFO - Initialising browser for dromaeo_css test... 09:41:44 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpWVDaBb/profile http://localhost:49267/getInfo.html) 09:41:47 INFO - PROCESS | 1651 | 2016-10-19 09:41:47.825 plugin-container[1653:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 09:41:47 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:47 INFO - PROCESS | 1651 | 2016-10-19 09:41:47.828 plugin-container[1653:7653] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c17, name = 'com.apple.CFPasteboardClient' 09:41:47 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:47 INFO - PROCESS | 1651 | 2016-10-19 09:41:47.828 plugin-container[1653:7653] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:41:47 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 09:41:47 INFO - PROCESS | 1651 | colorDepth:24 09:41:47 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 09:41:47 INFO - PROCESS | 1651 | __metrics 09:41:47 ERROR - PROCESS | 1651 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 09:41:48 INFO - TEST-INFO | 1651: exit 0 09:41:48 INFO - Browser initialized. 09:41:48 INFO - Running cycle 1/1 for dromaeo_css test... 09:41:48 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpWVDaBb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:41:51 INFO - PROCESS | 1654 | 2016-10-19 09:41:51.110 plugin-container[1655:7772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x453b, name = 'com.apple.tsm.portname' 09:41:51 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:51 INFO - PROCESS | 1654 | 2016-10-19 09:41:51.111 plugin-container[1655:7772] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 09:41:51 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:41:51 INFO - PROCESS | 1654 | 2016-10-19 09:41:51.111 plugin-container[1655:7772] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:42:00 INFO - PROCESS | 1654 | RSS: Main: 237297664 09:42:00 INFO - PROCESS | 1654 | 09:42:50 INFO - PROCESS | 1654 | 1476895370470 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:45:04 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 09:45:05 INFO - PROCESS | 1654 | RSS: Main: 249888768 09:45:05 INFO - PROCESS | 1654 | 09:48:07 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 09:48:08 INFO - PROCESS | 1654 | RSS: Main: 262135808 09:48:08 INFO - PROCESS | 1654 | 09:51:10 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 09:51:11 INFO - PROCESS | 1654 | RSS: Main: 268783616 09:51:11 INFO - PROCESS | 1654 | 09:54:12 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 09:54:13 INFO - PROCESS | 1654 | RSS: Main: 269578240 09:54:13 INFO - PROCESS | 1654 | 09:57:16 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 09:57:16 INFO - PROCESS | 1654 | RSS: Main: 274755584 09:57:16 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 10:00:19 INFO - PROCESS | 1654 | RSS: Main: 277688320 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | __start_tp_report 10:00:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 10:00:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 10:00:19 INFO - PROCESS | 1654 | |i|pagename|runs| 10:00:19 INFO - PROCESS | 1654 | |0;dojo.html;3113.828739419332;3391.8812841550553;3409.386310464117;3410.942607791583;3447.758656894018;5417.7020263885515;5590.636608620437;5682.147677848325;5706.31524217094;5774.884502309951;5630.662160270386;5693.77224911003;5730.79942202023;5816.272965879265;5843.707814609264;5020.849374518771;5169.767360468779;5179.19722443022;5227.059129356714;5382.569394448455;5330.760115794779;5385.3537575491;5397.082495975674;5400.324959380077;5457.808976685817;5124.308218390544;5297.284866543006;5301.098813201726;5309.442508536606;5350.892982140354;4470.441194850644;5114.897702045975;5194.298769666085;5238.161894096965;5308.973455132738;5149.382074151088;5170.224466330081;5192.350956130484;5231.476852314776;5258.290130832327;5636.915446268309;5684.37471878093;5755.280589926259;5786.392428795;5793.275840519935;6113.908291375633;6114.724837382284;6137.315698286919;6177.81466556004;6215.782447614311;6239.875202495925;6249.812505624793;6253.1245625612455;6254.124422580842;6266.624002559861;5863.912041319341;5885.323187833454;5886.937233978775;5937.079752638391;5943.0194017987415;4640.419947506562;5412.215228791882;6013.007853704177;6175.104609831549;6383.71273292703;6011.789587364421;6139.907901381483;6192.102145189013;6213.875722485525;6222.62664240147;6110.847228819315;6236.314005459396;6246.312905580383;6268.028455589391;6281.246250449975;5078.263651770547;6128.533679014861;6141.723622437001;6141.854248382324;6255.311915689362;6216.129741836146;6231.22109736283;6233.906491402633;6248.593841400295;6285.96857015712;4867.123917694778;4946.159152944008;4946.307516947631;4971.005798840247;4999.875003124951;4336.3278691802825;4386.71486353386;4395.340698895191;4428.357888106207;4465.151621191963;5597.80407685729;5846.7076646167525;5864.38423965486;5888.499477544371;5896.852578685567;6129.559553504922;6216.595921265103;6237.563370564108;6239.251289845157;6239.625622462667;5640.379558248589;5725.484706376363;5798.246227990334;5798.739056742456;5821.825345239649;5427.9185812212845;5462.535684466785;5496.285482887199;5503.449655034465;5531.419200983839;6357.618542887441;6390.456811171102;6407.807765767035;6408.551401401857;6448.935510644834;6122.989706698523;6146.569740118328;6167.92061389264;6227.221597300338;6236.438720514994;6221.502279817513;6335.588186767845;6336.112944187726;6364.96817515928;6367.554271200971;6198.783042593487;6203.100550420074;6203.4416902479;6228.906566401417;6231.626102433868;4926.704397736147;6203.2556093269095;6218.595791273553;6224.533160012927;6245.500359971283;6117.0662765122115;6141.486007784364;6142.877142457037;6199.473044791243;6213.689315534115;6068.544859135494;6165.136880836597;6182.598131121463;6207.162033125651;6359.427651511386;4903.975480122576;5717.342505611908;6021.066734656135;6035.69821508914;6104.786332478342;6131.693415329305;6150.138980542638;6161.537884658758;6201.875962480815;6229.937700622936;6045.153678484928;6048.334683184758;6055.757769689163;6064.120702498025;6064.730867632047 10:00:19 INFO - PROCESS | 1654 | |1;ext.html;516.2901011109724;517.4954419441048;529.2299703930784;531.3039917707803;562.2409746841763;39812.203755924864;41138.38292425616;41295.79352103243;41349.586504134946;41410.79294603527;42082.36876446848;42525;42569.7871510642;42593.78703106488;42608.57391426085;9507.619695212183;9659.71020869375;9672;9674.22606191503;9697.757556061084;9593.232541396672;9594.232461403106;9612.567434465465;9613.66352177674;9646.710598682052;9535.141837234647;9588.280878934072;9601.519924003773;9623.422594644344;9659.5170241488;9511.571979260916;9541.379810312308;9567.617295308179;9594.472304023277;9601.327907046509;7293.234210407937;9586.616535338579;9649.807003859954;9654.469004204766;9686.46724430156;40876.38685419721;41211.96970075742;41238.38142427866;41352.586474135176;41374.37938430926;2735.3298441881666;3261.266215101607;3324.011186576094;3328.417526932801;3429.3484237994703;30168.849155754302;30401.543976840367;30878;31235.375292494024;31389;3160.747140228776;3169.9841500792577;3184.6656101109515;3276.344731053775;3280.130765347185;482359;484987;487691.99999999645;488067;491532;61542.076868847005;61918.69040654858;61995;62017.68991155015;62138.68930655408;65260.67369663216;65719;65823.34176658269;65973;66234.66882665556;16265.593360165854;16418.014919104655;16496;16516.009039457673;16539.338426463048;12621.116521843567;12657.87342126567;12780.297083660487;12783.296918669377;12792.488300468063;18208.330878453933;19070.809291907182;19100.522486937938;19121.426357209308;19298.4210473686;3219.1630176154367;7748.690052397954;7849.293563579191;7904.723334683258;7964.601769911481;847.2840449819893;918.4397517514311;971.6599190283345;1062.0017183847158;1070.0331224339982;24124.87937560301;28807.56788648172;29778.702212978027;29941.40117197644;30251.546226806615;1046.7749433871754;1089.199438412766;1118.8992990630882;1170.8653504846882;1186.3949385813125;15355.692886142215;15486.845131548765;15600.219989000505;15851.682966340839;15921.601959950862;582276;583403;584955;588285;590613;57167;57380;58012;58087.709561452764;58150.41849581535;1358.9932050339883;1373.8420081690533;1384.3701115992199;1402.7334806386755;1405.6064301995457;28443.857780710965;28511.714882850905;28954.27614309659;29498;30125.096247112622;40351;41091;49524.00951980868;49684;49849.252261215384;514753;518427;519088;520410;522138;26405.471890562465;26780.464390712466;26805;26842.865785670947;26889;37206;45977.71256305568;46116.30825537552;47544.52455475539;47779.28331074967;33650.15874603154;33760.49359259564;34464.96605101851;38028.42957355586;38639.8408047759;34439.4834077494;37889.81055094707;37954;38229.235415292096;39292.60707392889;36718.632813671524;37787.62212377841;38023.61976380201;38139.85580432592;39638.60361396349;30489.390212196075;33547;35011.64988350186;35017;35081.29837403187;25234;25688.743112568634;25745;25981.22056338313;26240.73759262459 10:00:19 INFO - PROCESS | 1654 | |2;jquery.html;331.6550787181331;349.7149822894341;372.88254199927;385.1757239507453;400.5693879079988;18792.436226913196;19233.23067077315;19890.70163947542;20511.589768204627;20754.27360042399;19676.40970770879;20019.399418017445;20172.394828155142;20471.078801453932;20518;4948.727819969909;5529.087700529408;5819.359870414251;5836.883262334751;5853.034249348852;5602.2436971009;5731;5768.740406681688;5772.769089236425;5778.133280008012;4791.976040119795;5440.292761940942;5726.45598668128;5743.712814359286;5749.626274292178;5621.5221706154825;5682;5692.971535142339;5699.772009119631;5718.799842005551;4832.855014349575;4867.951320486821;4988.925166122511;5000.1249781288125;5001.724905130216;23488.647670284958;23533.9409726561;23897;24293.635595466083;24500.264992050088;2251.5609456156058;2316.7683231676697;2372.5966585680476;2565.551028569993;2827.9536571468484;14781.630459238497;14930.850691493164;15072.321745521478;15095.32071056805;15304.846951530453;1751.3695069774872;1779.297177614848;1803.5400972751995;1883.359657716383;1898.3166060218448;100235;104114;104879.4756026215;105758;106371.46814265956;10546.05085542305;10680.893191068146;10839.458027098772;11051.72370690739;11392;11046;13172.143810652276;13452.730945381234;13514.189148651112;13559;8274.793130171673;8363.247307742333;8430.735389691596;8556.486610803371;8559.828803423896;8494.53280069602;8522.27560657351;8548.957255213683;8633.870491942625;8733.38866279367;6364;6581.80254592363;6605.669716514155;6620.782038017222;6630.933690663128;1841.0426578179272;1871.8034606366407;1888.9905550472436;1894.507428068712;1894.725264836591;787.142015203431;890.3500444675424;918.8575770755543;942.4345392764286;1017.7404761785697;17332.13339333028;18397.26410943574;18734.625307493774;19100.2359905605;21087.68368474474;938.5213541094091;955.7180631713661;962.7063745557672;1050.8108540462638;1053.6536785758744;9816.26378021637;10225.897741022494;11327;11360.886391135982;11398.772024559463;490115;580412.0979395076;588712;593519.5832375106;597159;96388.51805740927;98871;125729.34217960214;131407;142325;422.6618705035959;442.16210281516345;444.1871375383041;448.177594114803;448.5514485514486;9447.574859131493;10035.046670566284;10596.947015264874;10675.839862401916;10844.620438284623;29655.81376744906;48923;53514.394568163014;54718.726406367714;54801;53785;75265.87101193596;80130.59934700289;80452.19547804681;82654;7673.386129109546;7928.326092282221;7929.960350198212;8044.517328960281;8090.555019473985;11251;12169.817452738393;12242.143049986771;12436.875631243935;12476.750464990831;10570.682879513626;10762.3542587445;10850.240483166102;10909.127269818555;10984.780304394028;9475.47884866339;10401;10457.947710261704;10570.312929659547;10623.203259755395;10909.781804364027;10935.671929842118;10995.615153469591;11096.778064438828;11152.665420037412;6328.208973878265;7905.6837726492595;7961.243681850215;7998.400119990907;8160;9623.807523849624;10547;10625.946870265909;10785.676429707122;10836.241463097507 10:00:19 INFO - PROCESS | 1654 | |3;mootools.html;1496.6931778985308;1527.0608575725928;1761.6652835961168;1897.1178402043047;1917.8082191780823;7982.201779822015;8258.174182581739;8392.454490458116;8957.865632015524;8982.236509896658;8455.577221138949;8769.60536775845;8816.955915220431;8905.15401036902;8922.286217102632;2345.64815277708;2404.3989002749313;2405.6391541268863;2406.362313986795;2407.5305315463493;2351.694279743631;2386.53462574798;2389.940251493718;2394.0543485323287;2398.556267090585;2370.158593699238;2374.9050037998545;2380.33350661815;2383.1539803369815;2406.4344878953434;2362.9645555316683;2371.92884213473;2375.5842727522613;2380.3811009137576;2384.0463814474174;2350.5298940212024;2354.1407386304045;2359.6696462495265;2371.1819422299277;2371.762823717632;8772.254358379545;8887.555622218928;8934.061923498004;8955.910440895637;8963.596638151299;5213.218017297398;5358.732063396815;5388.865278368072;5391.703456309901;5392.541633961078;3321.186309354224;3394.896658585945;3534.7525673202886;3606.3688854450365;3626.564812222524;948.966786162481;987.6592575561419;1045.163868904873;1055.6094245129352;1055.7888422315568;11599.420028998516;11614.535418583246;11646.883531164665;11709.004734597569;11749.942505174575;4359.367891655692;4608.147492713859;4617.953820461752;4743.430788305425;4849.442314133849;9942.652007179713;10008.899911000943;10020.649277275406;10024.699259022242;10048;4756.904861902812;5223.660462070029;5401.2168235606405;5427.29445172133;5434.456554344534;4403.427554417905;4554.931676024862;4750;4895.902081958412;4942.604591632661;3613.8735144269826;3810.904727381838;4011.8997025074023;4046.5548789633117;4055.6958228133008;1293.4179619171223;1300.5578103444875;1304.8238487804215;1314.3362601886176;1315.4540865540694;783.4789864739998;858.7252079334678;902.7833320003283;904.5115637555778;916.5966974531185;5458.61789674719;5597.272354593877;5771.567132465082;5846.239988801429;5848.619839710405;902.634433054614;977.4721650308895;1011.7774089700254;1016.6594190946116;1057.1912486947492;3779.527559055118;3792.867249646249;3816.885493435201;3892.377219644843;3905.7070719695566;9966.501674916226;10055.491676248621;10308.896911030944;10715.410652414037;10751.83872241917;10701.89298107009;10702.625408110678;10703.83944240837;10704.464776761131;10819.729506762236;7893.289603935674;8116.878246826184;8146.918530814734;8265.710700125466;8291.08798032216;5538.224648549287;5670.914936275876;5677.77288908439;5690.857728556819;5806.622569532967;9659.758506037406;9852.211823053995;9953.104220620178;10010.499475026074;10013.299069065095;8571.614277357408;8925.196732293865;10219.182465402899;10243.897561024294;10265.58937642486;6527.314631963575;6739.932600674127;6862.519623626498;7046.823829404101;7172.928270717226;8302.377321700777;8408.957955210184;8477;8559.486430814171;8566.91433085661;7222.205557388788;7506.7372641959455;7626.8855967159425;7687.346575540918;7741.303282704584;7171.462140339391;7296.671649775667;7334.3032411920785;7417;7466.35740137169;6847.760328388483;6848.2466928638805;6928;6946.79159625212;6962.721491140298;4796.184648609675;4852;4870.366852309319;4898.24077268024;4946.678465899705;4717.693349932243;4814.711117332971;4907.950920490749;4987.877727511426;4988.75056247179 10:00:19 INFO - PROCESS | 1654 | |4;prototype.html;271.5709179496396;299.41016198089767;305.7141572629591;310.9409212249672;314.5549048096943;5020.673656212343;5529.806456774014;5562.276904002479;5679.290088738908;5712.371639119699;4307.526172121072;5506.6321481762825;5584.52531534819;5586.329640443152;5606.971965140179;3368.34317308125;3728.906777330575;4276.187524370367;4303.870883873489;4327.891802704926;3945.684345252373;4220.240356735786;4314.180305741915;4322.092360604277;4325.913481730364;4152.68854835887;4175.728577642459;4177.415161877339;4185.539590645042;4203.47456567929;3668.4497325401135;3812.4281357796553;3861.0347413146715;4164.771392439207;4196.349565817303;3197.2965947491516;3223.88775872326;3631.5823680276844;3636.000099972529;3638.9272214555826;4578;5028.6031547743105;5427.3134798275105;5434.212039254286;5455.809046683347;3846.576876543578;4586.931196032062;4838.540338667821;4839.661223714342;4844.5155484451625;4686.742229177428;4870.951290487121;5063.0128224347445;5368.70472124033;5767.0772676372;3507.719382449398;3659.79871107089;3724.703637280999;3952.6047395260243;3970.642642162219;5077.466865979093;5797.942020579824;5962.807438512315;6248.656323902183;6297.811065668037;4018.714011516345;4021.778802165909;4032.334664780278;4035.414864844581;4082.9387559186634;3780.8109594520165;4026.6577340925764;4068.2270368629866;4094.63148316647;4094.7715685294293;4152.1695660867945;4733.834315798999;4789.3055506952;4796.448408433075;4800.423949126058;4886.828960986349;5938.554608404387;5984.760609575568;5987.760709379293;6009.519238461;1298.8246586710673;1312.3307113372248;1359.8572149924116;1369.5617402431126;1393.9233342166278;825.6490991328661;853.833502467013;853.9069990412282;921.9812107621116;933.6032186320922;4495.57292057254;4652.37166991557;5435.673859568438;5500.4224556420995;5512.145617429305;5956.086228861207;6014.368491308437;6016.42843929826;6050.273967123973;6051.394860513913;4924.581410580069;5477.835664930059;5495.945040549624;5496.862578435571;5578.609497335228;2829.7407653594364;3142.277276226481;3209.245827230599;3210.5665735125926;3212.506184624161;5657.377688454267;6137.7724455109155;6280.246370435486;6392.26488953767;6419.646919419383;4915;5076.959223359203;5313.388960269541;6333.453266170959;6452.870942581122;5627.915581266284;5734.6810233645665;5966.4642526926755;6010.9180347536985;6478.963365861534;5313.203019546944;5626.442581508991;5885.3820348862755;5891.970540147272;6173.98129308659;5323.920141197963;6126.081087836682;6136.417040381157;6183.721732521957;6188.907166392418;6069.757209711562;6443.553947776019;6468.2561505427475;6472.708728107341;6482.481401487776;3387.9666701656233;3876.972602260347;3966.790129010564;4039.333509970822;4041.2321658884716;5876.823695289148;6225.719842607003;6230.50155987529;6239.812805615838;6243.375662433902;4451.866444006685;4945.678530895624;5118.9506151238165;5305.363356397257;5986.700664966821;4902.975485122552;5926.940730592639;5928.377520360473;5956.553258505716;6038.849028774316;4907.852764417074;5576.498115169654;5859.9121013183985;5880.588358814842;5938.099808061379;6020.217371741645;6071;6081.118237855575;6087.665178415053;6102.542309326729;5140.30605868203;5349.679019258857;5496.422875598165;5591.455887571064;5774.393688662798 10:00:19 INFO - PROCESS | 1654 | |5;yui.html;712.8503014366983;714.6248219684667;715.8496715689705;740.4631642059508;828.1842385250529;15265.694686106284;16196.919015404936;16198.947068440573;16210.027398356107;16239.106849123293;14550.927245363784;14568.053076550043;14691.045082069659;14750.33623486943;14814;866.086278975682;879.1735768377737;885.6590212768066;886.171429713217;889.1242126505387;697.9651017449107;720.6684924934536;888.0586578227068;891.7324802559207;892.8214357128568;906.2885634776702;926.5182105305237;936.9109934556207;944.8535477001076;946.4132238012442;920.7790130368663;925.1904583489446;929.3076657889882;931.2270815223383;931.818295432391;685.8045457044768;693.4798900824381;693.6531734132933;694.9409300209488;695.2699665351361;13656.590302290948;13708.862911370958;13712.65718357049;13724.519641812489;13762.793558096637;11440.94279528597;13700.314984250748;13758.380872860746;13843.653908652264;14205.502807401692;12427.067969902204;12482.750344993141;12554.623361299176;12568.270513634141;12810.551630692975;3880.2821478026653;3916.7845768483007;4423.933640995388;4457.442819647544;4533.659975501851;113340.86659133386;113566.86433135645;113679;114201;116210;9953.751156221153;10506.804692339987;10909.018188363085;11002.614908478325;11327.32036077838;20079.698804517942;21770.673439898415;22002.119915203213;22039.66940495894;22068.33794986153;7471.51435156724;7812.984312039399;7924.603769811486;7970.521768693897;8033.317168040782;3909.7849618271266;4016.3172260715746;4308.870733877989;4339.349097635317;4456.598906098467;3688.299223147647;3823.0251285921913;3829.5596006459054;3834.0031591786337;3839.0210496323816;634.9460295874902;691.2603514239815;704.5490885833071;743.5241445474901;743.6690672650727;556.3629644057531;585.6370043178339;606.6208619612742;612.1429998002832;650.6974256970436;7260.600666963368;9419.586239695636;9579.329446938647;9812.754681133028;9846.852297215404;578.2511647416605;653.4543656047159;672.5090683800853;684.7979845945458;692.2281655953676;4652.302154676758;4667.579917807414;4718.764061796896;4730.905381892343;4803;96698.51650741842;136238;152915.47084529235;166197;167674;140425;141216;141299.29350353184;142590.2870485641;146506;32847;115275;116095;116924;117625.4118729401;8764.693235736719;9067.954660226656;9073.682421115229;9093.77265568366;9291.581878815334;55875.441245587026;65582;65801.34198657951;66943.66528167328;67756.6612166936;55935.88128237331;56617.71691141518;71452;79760;89984.1001590002;13079.970857992716;13197.80203296932;13311;13329.800052999406;13406.932965335502;13960;14147.36336864823;14291.857081429052;14830.851691482947;15529.611759706098;12680.936595316964;12792.488300467878;12901.354932253538;13224.471021159046;13371.665708356979;13256;13482.52811151605;13648.430430500222;13715.451381944611;13867.653308667364;13474;13598;13734.587962361145;13765.311734413439;13849.307534623431;9171.31215158879;10696.946515267375;11092.778144437227;11347.773044539228;11405.600803971822;10787.298825576312;11177.72055698614;11401.087912967108;11481.425928703698;11777.823332650189 10:00:19 INFO - PROCESS | 1654 | __end_tp_report 10:00:19 INFO - PROCESS | 1654 | __start_cc_report 10:00:19 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,367 10:00:19 INFO - PROCESS | 1654 | __end_cc_report 10:00:19 INFO - PROCESS | 1654 | __startTimestamp1476896419925__endTimestamp 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | ------- Summary: start ------- 10:00:19 INFO - PROCESS | 1654 | Number of tests: 6 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5735.10 Median:6013.01 stddev:656.89 (10.9%) stddev-sans-first:625.90 10:00:19 INFO - PROCESS | 1654 | Values: 3113.8 3391.9 3409.4 3410.9 3447.8 5417.7 5590.6 5682.1 5706.3 5774.9 5630.7 5693.8 5730.8 5816.3 5843.7 5020.8 5169.8 5179.2 5227.1 5382.6 5330.8 5385.4 5397.1 5400.3 5457.8 5124.3 5297.3 5301.1 5309.4 5350.9 4470.4 5114.9 5194.3 5238.2 5309.0 5149.4 5170.2 5192.4 5231.5 5258.3 5636.9 5684.4 5755.3 5786.4 5793.3 6113.9 6114.7 6137.3 6177.8 6215.8 6239.9 6249.8 6253.1 6254.1 6266.6 5863.9 5885.3 5886.9 5937.1 5943.0 4640.4 5412.2 6013.0 6175.1 6383.7 6011.8 6139.9 6192.1 6213.9 6222.6 6110.8 6236.3 6246.3 6268.0 6281.2 5078.3 6128.5 6141.7 6141.9 6255.3 6216.1 6231.2 6233.9 6248.6 6286.0 4867.1 4946.2 4946.3 4971.0 4999.9 4336.3 4386.7 4395.3 4428.4 4465.2 5597.8 5846.7 5864.4 5888.5 5896.9 6129.6 6216.6 6237.6 6239.3 6239.6 5640.4 5725.5 5798.2 5798.7 5821.8 5427.9 5462.5 5496.3 5503.4 5531.4 6357.6 6390.5 6407.8 6408.6 6448.9 6123.0 6146.6 6167.9 6227.2 6236.4 6221.5 6335.6 6336.1 6365.0 6367.6 6198.8 6203.1 6203.4 6228.9 6231.6 4926.7 6203.3 6218.6 6224.5 6245.5 6117.1 6141.5 6142.9 6199.5 6213.7 6068.5 6165.1 6182.6 6207.2 6359.4 4904.0 5717.3 6021.1 6035.7 6104.8 6131.7 6150.1 6161.5 6201.9 6229.9 6045.2 6048.3 6055.8 6064.1 6064.7 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:66886.93 Median:40613.69 stddev:141854.65 (349.3%) stddev-sans-first:142165.06 10:00:19 INFO - PROCESS | 1654 | Values: 516.3 517.5 529.2 531.3 562.2 39812.2 41138.4 41295.8 41349.6 41410.8 42082.4 42525.0 42569.8 42593.8 42608.6 9507.6 9659.7 9672.0 9674.2 9697.8 9593.2 9594.2 9612.6 9613.7 9646.7 9535.1 9588.3 9601.5 9623.4 9659.5 9511.6 9541.4 9567.6 9594.5 9601.3 7293.2 9586.6 9649.8 9654.5 9686.5 40876.4 41212.0 41238.4 41352.6 41374.4 2735.3 3261.3 3324.0 3328.4 3429.3 30168.8 30401.5 30878.0 31235.4 31389.0 3160.7 3170.0 3184.7 3276.3 3280.1 482359.0 484987.0 487692.0 488067.0 491532.0 61542.1 61918.7 61995.0 62017.7 62138.7 65260.7 65719.0 65823.3 65973.0 66234.7 16265.6 16418.0 16496.0 16516.0 16539.3 12621.1 12657.9 12780.3 12783.3 12792.5 18208.3 19070.8 19100.5 19121.4 19298.4 3219.2 7748.7 7849.3 7904.7 7964.6 847.3 918.4 971.7 1062.0 1070.0 24124.9 28807.6 29778.7 29941.4 30251.5 1046.8 1089.2 1118.9 1170.9 1186.4 15355.7 15486.8 15600.2 15851.7 15921.6 582276.0 583403.0 584955.0 588285.0 590613.0 57167.0 57380.0 58012.0 58087.7 58150.4 1359.0 1373.8 1384.4 1402.7 1405.6 28443.9 28511.7 28954.3 29498.0 30125.1 40351.0 41091.0 49524.0 49684.0 49849.3 514753.0 518427.0 519088.0 520410.0 522138.0 26405.5 26780.5 26805.0 26842.9 26889.0 37206.0 45977.7 46116.3 47544.5 47779.3 33650.2 33760.5 34465.0 38028.4 38639.8 34439.5 37889.8 37954.0 38229.2 39292.6 36718.6 37787.6 38023.6 38139.9 39638.6 30489.4 33547.0 35011.6 35017.0 35081.3 25234.0 25688.7 25745.0 25981.2 26240.7 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:33031.70 Median:2696.75 stddev:95165.14 (3528.9%) stddev-sans-first:95400.43 10:00:19 INFO - PROCESS | 1654 | Values: 331.7 349.7 372.9 385.2 400.6 18792.4 19233.2 19890.7 20511.6 20754.3 19676.4 20019.4 20172.4 20471.1 20518.0 4948.7 5529.1 5819.4 5836.9 5853.0 5602.2 5731.0 5768.7 5772.8 5778.1 4792.0 5440.3 5726.5 5743.7 5749.6 5621.5 5682.0 5693.0 5699.8 5718.8 4832.9 4868.0 4988.9 5000.1 5001.7 23488.6 23533.9 23897.0 24293.6 24500.3 2251.6 2316.8 2372.6 2565.6 2828.0 14781.6 14930.9 15072.3 15095.3 15304.8 1751.4 1779.3 1803.5 1883.4 1898.3 100235.0 104114.0 104879.5 105758.0 106371.5 10546.1 10680.9 10839.5 11051.7 11392.0 11046.0 13172.1 13452.7 13514.2 13559.0 8274.8 8363.2 8430.7 8556.5 8559.8 8494.5 8522.3 8549.0 8633.9 8733.4 6364.0 6581.8 6605.7 6620.8 6630.9 1841.0 1871.8 1889.0 1894.5 1894.7 787.1 890.4 918.9 942.4 1017.7 17332.1 18397.3 18734.6 19100.2 21087.7 938.5 955.7 962.7 1050.8 1053.7 9816.3 10225.9 11327.0 11360.9 11398.8 490115.0 580412.1 588712.0 593519.6 597159.0 96388.5 98871.0 125729.3 131407.0 142325.0 422.7 442.2 444.2 448.2 448.6 9447.6 10035.0 10596.9 10675.8 10844.6 29655.8 48923.0 53514.4 54718.7 54801.0 53785.0 75265.9 80130.6 80452.2 82654.0 7673.4 7928.3 7930.0 8044.5 8090.6 11251.0 12169.8 12242.1 12436.9 12476.8 10570.7 10762.4 10850.2 10909.1 10984.8 9475.5 10401.0 10457.9 10570.3 10623.2 10909.8 10935.7 10995.6 11096.8 11152.7 6328.2 7905.7 7961.2 7998.4 8160.0 9623.8 10547.0 10625.9 10785.7 10836.2 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5663.89 Median:4850.72 stddev:3165.26 (65.3%) stddev-sans-first:3158.65 10:00:19 INFO - PROCESS | 1654 | Values: 1496.7 1527.1 1761.7 1897.1 1917.8 7982.2 8258.2 8392.5 8957.9 8982.2 8455.6 8769.6 8817.0 8905.2 8922.3 2345.6 2404.4 2405.6 2406.4 2407.5 2351.7 2386.5 2389.9 2394.1 2398.6 2370.2 2374.9 2380.3 2383.2 2406.4 2363.0 2371.9 2375.6 2380.4 2384.0 2350.5 2354.1 2359.7 2371.2 2371.8 8772.3 8887.6 8934.1 8955.9 8963.6 5213.2 5358.7 5388.9 5391.7 5392.5 3321.2 3394.9 3534.8 3606.4 3626.6 949.0 987.7 1045.2 1055.6 1055.8 11599.4 11614.5 11646.9 11709.0 11749.9 4359.4 4608.1 4618.0 4743.4 4849.4 9942.7 10008.9 10020.6 10024.7 10048.0 4756.9 5223.7 5401.2 5427.3 5434.5 4403.4 4554.9 4750.0 4895.9 4942.6 3613.9 3810.9 4011.9 4046.6 4055.7 1293.4 1300.6 1304.8 1314.3 1315.5 783.5 858.7 902.8 904.5 916.6 5458.6 5597.3 5771.6 5846.2 5848.6 902.6 977.5 1011.8 1016.7 1057.2 3779.5 3792.9 3816.9 3892.4 3905.7 9966.5 10055.5 10308.9 10715.4 10751.8 10701.9 10702.6 10703.8 10704.5 10819.7 7893.3 8116.9 8146.9 8265.7 8291.1 5538.2 5670.9 5677.8 5690.9 5806.6 9659.8 9852.2 9953.1 10010.5 10013.3 8571.6 8925.2 10219.2 10243.9 10265.6 6527.3 6739.9 6862.5 7046.8 7172.9 8302.4 8409.0 8477.0 8559.5 8566.9 7222.2 7506.7 7626.9 7687.3 7741.3 7171.5 7296.7 7334.3 7417.0 7466.4 6847.8 6848.2 6928.0 6946.8 6962.7 4796.2 4852.0 4870.4 4898.2 4946.7 4717.7 4814.7 4908.0 4987.9 4988.8 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4718.72 Median:5309.28 stddev:1495.16 (28.2%) stddev-sans-first:1461.62 10:00:19 INFO - PROCESS | 1654 | Values: 271.6 299.4 305.7 310.9 314.6 5020.7 5529.8 5562.3 5679.3 5712.4 4307.5 5506.6 5584.5 5586.3 5607.0 3368.3 3728.9 4276.2 4303.9 4327.9 3945.7 4220.2 4314.2 4322.1 4325.9 4152.7 4175.7 4177.4 4185.5 4203.5 3668.4 3812.4 3861.0 4164.8 4196.3 3197.3 3223.9 3631.6 3636.0 3638.9 4578.0 5028.6 5427.3 5434.2 5455.8 3846.6 4586.9 4838.5 4839.7 4844.5 4686.7 4871.0 5063.0 5368.7 5767.1 3507.7 3659.8 3724.7 3952.6 3970.6 5077.5 5797.9 5962.8 6248.7 6297.8 4018.7 4021.8 4032.3 4035.4 4082.9 3780.8 4026.7 4068.2 4094.6 4094.8 4152.2 4733.8 4789.3 4796.4 4800.4 4886.8 5938.6 5984.8 5987.8 6009.5 1298.8 1312.3 1359.9 1369.6 1393.9 825.6 853.8 853.9 922.0 933.6 4495.6 4652.4 5435.7 5500.4 5512.1 5956.1 6014.4 6016.4 6050.3 6051.4 4924.6 5477.8 5495.9 5496.9 5578.6 2829.7 3142.3 3209.2 3210.6 3212.5 5657.4 6137.8 6280.2 6392.3 6419.6 4915.0 5077.0 5313.4 6333.5 6452.9 5627.9 5734.7 5966.5 6010.9 6479.0 5313.2 5626.4 5885.4 5892.0 6174.0 5323.9 6126.1 6136.4 6183.7 6188.9 6069.8 6443.6 6468.3 6472.7 6482.5 3388.0 3877.0 3966.8 4039.3 4041.2 5876.8 6225.7 6230.5 6239.8 6243.4 4451.9 4945.7 5119.0 5305.4 5986.7 4903.0 5926.9 5928.4 5956.6 6038.8 4907.9 5576.5 5859.9 5880.6 5938.1 6020.2 6071.0 6081.1 6087.7 6102.5 5140.3 5349.7 5496.4 5591.5 5774.4 10:00:19 INFO - PROCESS | 1654 | 10:00:19 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24397.54 Median:3836.51 stddev:39825.46 (1038.1%) stddev-sans-first:39897.48 10:00:19 INFO - PROCESS | 1654 | Values: 712.9 714.6 715.8 740.5 828.2 15265.7 16196.9 16198.9 16210.0 16239.1 14550.9 14568.1 14691.0 14750.3 14814.0 866.1 879.2 885.7 886.2 889.1 698.0 720.7 888.1 891.7 892.8 906.3 926.5 936.9 944.9 946.4 920.8 925.2 929.3 931.2 931.8 685.8 693.5 693.7 694.9 695.3 13656.6 13708.9 13712.7 13724.5 13762.8 11440.9 13700.3 13758.4 13843.7 14205.5 12427.1 12482.8 12554.6 12568.3 12810.6 3880.3 3916.8 4423.9 4457.4 4533.7 113340.9 113566.9 113679.0 114201.0 116210.0 9953.8 10506.8 10909.0 11002.6 11327.3 20079.7 21770.7 22002.1 22039.7 22068.3 7471.5 7813.0 7924.6 7970.5 8033.3 3909.8 4016.3 4308.9 4339.3 4456.6 3688.3 3823.0 3829.6 3834.0 3839.0 634.9 691.3 704.5 743.5 743.7 556.4 585.6 606.6 612.1 650.7 7260.6 9419.6 9579.3 9812.8 9846.9 578.3 653.5 672.5 684.8 692.2 4652.3 4667.6 4718.8 4730.9 4803.0 96698.5 136238.0 152915.5 166197.0 167674.0 140425.0 141216.0 141299.3 142590.3 146506.0 32847.0 115275.0 116095.0 116924.0 117625.4 8764.7 9068.0 9073.7 9093.8 9291.6 55875.4 65582.0 65801.3 66943.7 67756.7 55935.9 56617.7 71452.0 79760.0 89984.1 13080.0 13197.8 13311.0 13329.8 13406.9 13960.0 14147.4 14291.9 14830.9 15529.6 12680.9 12792.5 12901.4 13224.5 13371.7 13256.0 13482.5 13648.4 13715.5 13867.7 13474.0 13598.0 13734.6 13765.3 13849.3 9171.3 10696.9 11092.8 11347.8 11405.6 10787.3 11177.7 11401.1 11481.4 11777.8 10:00:19 INFO - PROCESS | 1654 | -------- Summary: end -------- 10:00:19 INFO - PROCESS | 1654 | 10:00:20 INFO - TEST-INFO | 1654: exit 0 10:00:20 INFO - TEST-OK | dromaeo_css | took 1115841ms 10:00:20 INFO - TEST-START | kraken 10:00:20 INFO - operating with platform_type : mac_ 10:00:20 INFO - Initialising browser for kraken test... 10:00:20 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpPvsGl7/profile http://localhost:49267/getInfo.html) 10:00:23 INFO - PROCESS | 1662 | 2016-10-19 10:00:23.380 plugin-container[1663:9839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4443, name = 'com.apple.tsm.portname' 10:00:23 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:23 INFO - PROCESS | 1662 | 2016-10-19 10:00:23.383 plugin-container[1663:9839] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 10:00:23 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:23 INFO - PROCESS | 1662 | 2016-10-19 10:00:23.383 plugin-container[1663:9839] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:00:23 INFO - PROCESS | 1662 | __metrics Screen width/height:1600/1200 10:00:23 INFO - PROCESS | 1662 | colorDepth:24 10:00:23 INFO - PROCESS | 1662 | Browser inner width/height: 1024/689 10:00:23 INFO - PROCESS | 1662 | __metrics 10:00:23 ERROR - PROCESS | 1662 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 10:00:24 INFO - TEST-INFO | 1662: exit 0 10:00:24 INFO - Browser initialized. 10:00:24 INFO - Running cycle 1/1 for kraken test... 10:00:24 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/T/tmpPvsGl7/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:00:26 INFO - PROCESS | 1664 | 2016-10-19 10:00:26.500 plugin-container[1665:9953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 10:00:26 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:26 INFO - PROCESS | 1664 | 2016-10-19 10:00:26.501 plugin-container[1665:9953] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 10:00:26 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:00:26 INFO - PROCESS | 1664 | 2016-10-19 10:00:26.501 plugin-container[1665:9953] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:00:36 INFO - PROCESS | 1664 | RSS: Main: 237015040 10:00:36 INFO - PROCESS | 1664 | 10:00:39 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 10:00:40 INFO - PROCESS | 1664 | RSS: Main: 249393152 10:00:40 INFO - PROCESS | 1664 | 10:00:42 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 10:00:43 INFO - PROCESS | 1664 | RSS: Main: 256966656 10:00:43 INFO - PROCESS | 1664 | 10:00:47 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 10:00:47 INFO - PROCESS | 1664 | RSS: Main: 259506176 10:00:47 INFO - PROCESS | 1664 | 10:00:49 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 10:00:50 INFO - PROCESS | 1664 | RSS: Main: 261738496 10:00:50 INFO - PROCESS | 1664 | 10:00:52 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:00:52 INFO - PROCESS | 1664 | RSS: Main: 262881280 10:00:52 INFO - PROCESS | 1664 | 10:00:55 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 10:00:56 INFO - PROCESS | 1664 | RSS: Main: 262508544 10:00:56 INFO - PROCESS | 1664 | 10:00:59 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 10:00:59 INFO - PROCESS | 1664 | RSS: Main: 262742016 10:00:59 INFO - PROCESS | 1664 | 10:01:03 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 10:01:03 INFO - PROCESS | 1664 | RSS: Main: 262942720 10:01:03 INFO - PROCESS | 1664 | 10:01:05 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:01:05 INFO - PROCESS | 1664 | RSS: Main: 262688768 10:01:05 INFO - PROCESS | 1664 | 10:01:07 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:01:08 INFO - PROCESS | 1664 | RSS: Main: 262705152 10:01:08 INFO - PROCESS | 1664 | 10:01:10 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:01:11 INFO - PROCESS | 1664 | RSS: Main: 262729728 10:01:11 INFO - PROCESS | 1664 | 10:01:14 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:01:15 INFO - PROCESS | 1664 | RSS: Main: 263311360 10:01:15 INFO - PROCESS | 1664 | 10:01:18 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:01:18 INFO - PROCESS | 1664 | RSS: Main: 262836224 10:01:18 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 10:01:20 INFO - PROCESS | 1664 | RSS: Main: 264376320 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | __start_tp_report 10:01:20 INFO - PROCESS | 1664 | _x_x_mozilla_page_load 10:01:20 INFO - PROCESS | 1664 | _x_x_mozilla_page_load_details 10:01:20 INFO - PROCESS | 1664 | |i|pagename|runs| 10:01:20 INFO - PROCESS | 1664 | |0;ai-astar;106;120;162;116;117;118;116;118;118;118 10:01:20 INFO - PROCESS | 1664 | |1;audio-beat-detection;150;145;145;183;119;117;119;101;174;110 10:01:20 INFO - PROCESS | 1664 | |2;audio-dft;274;210;271;275;194;199;196;201;192;196 10:01:20 INFO - PROCESS | 1664 | |3;audio-fft;83;61;59;83;55;57;60;60;69;52 10:01:20 INFO - PROCESS | 1664 | |4;audio-oscillator;98;101;121;82;69;69;88;70;57;97 10:01:20 INFO - PROCESS | 1664 | |5;imaging-gaussian-blur;90;89;89;87;91;87;90;98;118;87 10:01:20 INFO - PROCESS | 1664 | |6;imaging-darkroom;104;97;131;97;94;99;154;98;152;91 10:01:20 INFO - PROCESS | 1664 | |7;imaging-desaturate;135;136;87;71;140;113;149;101;79;142 10:01:20 INFO - PROCESS | 1664 | |8;json-parse-financial;57;55;57;49;52;58;50;50;72;87 10:01:20 INFO - PROCESS | 1664 | |9;json-stringify-tinderbox;67;76;70;81;73;70;70;69;70;80 10:01:20 INFO - PROCESS | 1664 | |10;stanford-crypto-aes;90;111;93;97;108;109;81;104;112;112 10:01:20 INFO - PROCESS | 1664 | |11;stanford-crypto-ccm;150;162;163;145;146;137;156;168;212;166 10:01:20 INFO - PROCESS | 1664 | |12;stanford-crypto-pbkdf2;175;176;173;175;175;175;199;242;200;175 10:01:20 INFO - PROCESS | 1664 | |13;stanford-crypto-sha256-iterative;71;69;69;73;71;59;75;92;91;90 10:01:20 INFO - PROCESS | 1664 | __end_tp_report 10:01:20 INFO - PROCESS | 1664 | __start_cc_report 10:01:20 INFO - PROCESS | 1664 | _x_x_mozilla_cycle_collect,929 10:01:20 INFO - PROCESS | 1664 | __end_cc_report 10:01:20 INFO - PROCESS | 1664 | __startTimestamp1476896480836__endTimestamp 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | ------- Summary: start ------- 10:01:20 INFO - PROCESS | 1664 | Number of tests: 14 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#0] ai-astar Cycles:10 Average:120.90 Median:118.00 stddev:14.94 (12.7%) stddev-sans-first:14.84 10:01:20 INFO - PROCESS | 1664 | Values: 106.0 120.0 162.0 116.0 117.0 118.0 116.0 118.0 118.0 118.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#1] audio-beat-detection Cycles:10 Average:136.30 Median:145.00 stddev:27.59 (19.0%) stddev-sans-first:28.81 10:01:20 INFO - PROCESS | 1664 | Values: 150.0 145.0 145.0 183.0 119.0 117.0 119.0 101.0 174.0 110.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#2] audio-dft Cycles:10 Average:220.80 Median:205.50 stddev:36.59 (17.8%) stddev-sans-first:33.36 10:01:20 INFO - PROCESS | 1664 | Values: 274.0 210.0 271.0 275.0 194.0 199.0 196.0 201.0 192.0 196.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#3] audio-fft Cycles:10 Average:63.90 Median:60.50 stddev:10.99 (18.2%) stddev-sans-first:9.23 10:01:20 INFO - PROCESS | 1664 | Values: 83.0 61.0 59.0 83.0 55.0 57.0 60.0 60.0 69.0 52.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#4] audio-oscillator Cycles:10 Average:85.20 Median:76.00 stddev:19.45 (25.6%) stddev-sans-first:20.07 10:01:20 INFO - PROCESS | 1664 | Values: 98.0 101.0 121.0 82.0 69.0 69.0 88.0 70.0 57.0 97.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:89.50 stddev:9.49 (10.6%) stddev-sans-first:10.02 10:01:20 INFO - PROCESS | 1664 | Values: 90.0 89.0 89.0 87.0 91.0 87.0 90.0 98.0 118.0 87.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#6] imaging-darkroom Cycles:10 Average:111.70 Median:95.50 stddev:24.42 (25.6%) stddev-sans-first:25.74 10:01:20 INFO - PROCESS | 1664 | Values: 104.0 97.0 131.0 97.0 94.0 99.0 154.0 98.0 152.0 91.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#7] imaging-desaturate Cycles:10 Average:115.30 Median:145.50 stddev:28.99 (19.9%) stddev-sans-first:29.86 10:01:20 INFO - PROCESS | 1664 | Values: 135.0 136.0 87.0 71.0 140.0 113.0 149.0 101.0 79.0 142.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#8] json-parse-financial Cycles:10 Average:58.70 Median:57.00 stddev:11.96 (21.0%) stddev-sans-first:12.67 10:01:20 INFO - PROCESS | 1664 | Values: 57.0 55.0 57.0 49.0 52.0 58.0 50.0 50.0 72.0 87.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:71.50 stddev:4.81 (6.7%) stddev-sans-first:4.66 10:01:20 INFO - PROCESS | 1664 | Values: 67.0 76.0 70.0 81.0 73.0 70.0 70.0 69.0 70.0 80.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#10] stanford-crypto-aes Cycles:10 Average:101.70 Median:96.50 stddev:10.85 (11.2%) stddev-sans-first:10.65 10:01:20 INFO - PROCESS | 1664 | Values: 90.0 111.0 93.0 97.0 108.0 109.0 81.0 104.0 112.0 112.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#11] stanford-crypto-ccm Cycles:10 Average:160.50 Median:162.50 stddev:20.76 (12.8%) stddev-sans-first:21.67 10:01:20 INFO - PROCESS | 1664 | Values: 150.0 162.0 163.0 145.0 146.0 137.0 156.0 168.0 212.0 166.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.50 Median:175.50 stddev:22.04 (12.6%) stddev-sans-first:22.98 10:01:20 INFO - PROCESS | 1664 | Values: 175.0 176.0 173.0 175.0 175.0 175.0 199.0 242.0 200.0 175.0 10:01:20 INFO - PROCESS | 1664 | 10:01:20 INFO - PROCESS | 1664 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:74.00 stddev:11.18 (15.1%) stddev-sans-first:11.71 10:01:20 INFO - PROCESS | 1664 | Values: 71.0 69.0 69.0 73.0 71.0 59.0 75.0 92.0 91.0 90.0 10:01:20 INFO - PROCESS | 1664 | -------- Summary: end -------- 10:01:20 INFO - PROCESS | 1664 | 10:01:21 INFO - TEST-INFO | 1664: exit 0 10:01:21 INFO - TEST-OK | kraken | took 61156ms 10:01:21 INFO - SUITE-END | took 1176s 10:01:21 INFO - Completed test suite (00:19:37) 10:01:21 INFO - javascript benchmark 10:01:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7325.3943516138215, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3113.828739419332, 3391.8812841550553, 3409.386310464117, 3410.942607791583, 3447.758656894018, 5417.7020263885515, 5590.636608620437, 5682.147677848325, 5706.31524217094, 5774.884502309951, 5630.662160270386, 5693.77224911003, 5730.79942202023, 5816.272965879265, 5843.707814609264, 5020.849374518771, 5169.767360468779, 5179.19722443022, 5227.059129356714, 5382.569394448455, 5330.760115794779, 5385.3537575491, 5397.082495975674, 5400.324959380077, 5457.808976685817, 5124.308218390544, 5297.284866543006, 5301.098813201726, 5309.442508536606, 5350.892982140354, 4470.441194850644, 5114.897702045975, 5194.298769666085, 5238.161894096965, 5308.973455132738, 5149.382074151088, 5170.224466330081, 5192.350956130484, 5231.476852314776, 5258.290130832327, 5636.915446268309, 5684.37471878093, 5755.280589926259, 5786.392428795, 5793.275840519935, 6113.908291375633, 6114.724837382284, 6137.315698286919, 6177.81466556004, 6215.782447614311, 6239.875202495925, 6249.812505624793, 6253.1245625612455, 6254.124422580842, 6266.624002559861, 5863.912041319341, 5885.323187833454, 5886.937233978775, 5937.079752638391, 5943.0194017987415, 4640.419947506562, 5412.215228791882, 6013.007853704177, 6175.104609831549, 6383.71273292703, 6011.789587364421, 6139.907901381483, 6192.102145189013, 6213.875722485525, 6222.62664240147, 6110.847228819315, 6236.314005459396, 6246.312905580383, 6268.028455589391, 6281.246250449975, 5078.263651770547, 6128.533679014861, 6141.723622437001, 6141.854248382324, 6255.311915689362, 6216.129741836146, 6231.22109736283, 6233.906491402633, 6248.593841400295, 6285.96857015712, 4867.123917694778, 4946.159152944008, 4946.307516947631, 4971.005798840247, 4999.875003124951, 4336.3278691802825, 4386.71486353386, 4395.340698895191, 4428.357888106207, 4465.151621191963, 5597.80407685729, 5846.7076646167525, 5864.38423965486, 5888.499477544371, 5896.852578685567, 6129.559553504922, 6216.595921265103, 6237.563370564108, 6239.251289845157, 6239.625622462667, 5640.379558248589, 5725.484706376363, 5798.246227990334, 5798.739056742456, 5821.825345239649, 5427.9185812212845, 5462.535684466785, 5496.285482887199, 5503.449655034465, 5531.419200983839, 6357.618542887441, 6390.456811171102, 6407.807765767035, 6408.551401401857, 6448.935510644834, 6122.989706698523, 6146.569740118328, 6167.92061389264, 6227.221597300338, 6236.438720514994, 6221.502279817513, 6335.588186767845, 6336.112944187726, 6364.96817515928, 6367.554271200971, 6198.783042593487, 6203.100550420074, 6203.4416902479, 6228.906566401417, 6231.626102433868, 4926.704397736147, 6203.2556093269095, 6218.595791273553, 6224.533160012927, 6245.500359971283, 6117.0662765122115, 6141.486007784364, 6142.877142457037, 6199.473044791243, 6213.689315534115, 6068.544859135494, 6165.136880836597, 6182.598131121463, 6207.162033125651, 6359.427651511386, 4903.975480122576, 5717.342505611908, 6021.066734656135, 6035.69821508914, 6104.786332478342, 6131.693415329305, 6150.138980542638, 6161.537884658758, 6201.875962480815, 6229.937700622936, 6045.153678484928, 6048.334683184758, 6055.757769689163, 6064.120702498025, 6064.730867632047], "value": 5694.298739449587, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [516.2901011109724, 517.4954419441048, 529.2299703930784, 531.3039917707803, 562.2409746841763, 39812.203755924864, 41138.38292425616, 41295.79352103243, 41349.586504134946, 41410.79294603527, 42082.36876446848, 42525.0, 42569.7871510642, 42593.78703106488, 42608.57391426085, 9507.619695212183, 9659.71020869375, 9672.0, 9674.22606191503, 9697.757556061084, 9593.232541396672, 9594.232461403106, 9612.567434465465, 9613.66352177674, 9646.710598682052, 9535.141837234647, 9588.280878934072, 9601.519924003773, 9623.422594644344, 9659.5170241488, 9511.571979260916, 9541.379810312308, 9567.617295308179, 9594.472304023277, 9601.327907046509, 7293.234210407937, 9586.616535338579, 9649.807003859954, 9654.469004204766, 9686.46724430156, 40876.38685419721, 41211.96970075742, 41238.38142427866, 41352.586474135176, 41374.37938430926, 2735.3298441881666, 3261.266215101607, 3324.011186576094, 3328.417526932801, 3429.3484237994703, 30168.849155754302, 30401.543976840367, 30878.0, 31235.375292494024, 31389.0, 3160.747140228776, 3169.9841500792577, 3184.6656101109515, 3276.344731053775, 3280.130765347185, 482359.0, 484987.0, 487691.99999999645, 488067.0, 491532.0, 61542.076868847005, 61918.69040654858, 61995.0, 62017.68991155015, 62138.68930655408, 65260.67369663216, 65719.0, 65823.34176658269, 65973.0, 66234.66882665556, 16265.593360165854, 16418.014919104655, 16496.0, 16516.009039457673, 16539.338426463048, 12621.116521843567, 12657.87342126567, 12780.297083660487, 12783.296918669377, 12792.488300468063, 18208.330878453933, 19070.809291907182, 19100.522486937938, 19121.426357209308, 19298.4210473686, 3219.1630176154367, 7748.690052397954, 7849.293563579191, 7904.723334683258, 7964.601769911481, 847.2840449819893, 918.4397517514311, 971.6599190283345, 1062.0017183847158, 1070.0331224339982, 24124.87937560301, 28807.56788648172, 29778.702212978027, 29941.40117197644, 30251.546226806615, 1046.7749433871754, 1089.199438412766, 1118.8992990630882, 1170.8653504846882, 1186.3949385813125, 15355.692886142215, 15486.845131548765, 15600.219989000505, 15851.682966340839, 15921.601959950862, 582276.0, 583403.0, 584955.0, 588285.0, 590613.0, 57167.0, 57380.0, 58012.0, 58087.709561452764, 58150.41849581535, 1358.9932050339883, 1373.8420081690533, 1384.3701115992199, 1402.7334806386755, 1405.6064301995457, 28443.857780710965, 28511.714882850905, 28954.27614309659, 29498.0, 30125.096247112622, 40351.0, 41091.0, 49524.00951980868, 49684.0, 49849.252261215384, 514753.0, 518427.0, 519088.0, 520410.0, 522138.0, 26405.471890562465, 26780.464390712466, 26805.0, 26842.865785670947, 26889.0, 37206.0, 45977.71256305568, 46116.30825537552, 47544.52455475539, 47779.28331074967, 33650.15874603154, 33760.49359259564, 34464.96605101851, 38028.42957355586, 38639.8408047759, 34439.4834077494, 37889.81055094707, 37954.0, 38229.235415292096, 39292.60707392889, 36718.632813671524, 37787.62212377841, 38023.61976380201, 38139.85580432592, 39638.60361396349, 30489.390212196075, 33547.0, 35011.64988350186, 35017.0, 35081.29837403187, 25234.0, 25688.743112568634, 25745.0, 25981.22056338313, 26240.73759262459], "value": 19758.90046331652, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.6550787181331, 349.7149822894341, 372.88254199927, 385.1757239507453, 400.5693879079988, 18792.436226913196, 19233.23067077315, 19890.70163947542, 20511.589768204627, 20754.27360042399, 19676.40970770879, 20019.399418017445, 20172.394828155142, 20471.078801453932, 20518.0, 4948.727819969909, 5529.087700529408, 5819.359870414251, 5836.883262334751, 5853.034249348852, 5602.2436971009, 5731.0, 5768.740406681688, 5772.769089236425, 5778.133280008012, 4791.976040119795, 5440.292761940942, 5726.45598668128, 5743.712814359286, 5749.626274292178, 5621.5221706154825, 5682.0, 5692.971535142339, 5699.772009119631, 5718.799842005551, 4832.855014349575, 4867.951320486821, 4988.925166122511, 5000.1249781288125, 5001.724905130216, 23488.647670284958, 23533.9409726561, 23897.0, 24293.635595466083, 24500.264992050088, 2251.5609456156058, 2316.7683231676697, 2372.5966585680476, 2565.551028569993, 2827.9536571468484, 14781.630459238497, 14930.850691493164, 15072.321745521478, 15095.32071056805, 15304.846951530453, 1751.3695069774872, 1779.297177614848, 1803.5400972751995, 1883.359657716383, 1898.3166060218448, 100235.0, 104114.0, 104879.4756026215, 105758.0, 106371.46814265956, 10546.05085542305, 10680.893191068146, 10839.458027098772, 11051.72370690739, 11392.0, 11046.0, 13172.143810652276, 13452.730945381234, 13514.189148651112, 13559.0, 8274.793130171673, 8363.247307742333, 8430.735389691596, 8556.486610803371, 8559.828803423896, 8494.53280069602, 8522.27560657351, 8548.957255213683, 8633.870491942625, 8733.38866279367, 6364.0, 6581.80254592363, 6605.669716514155, 6620.782038017222, 6630.933690663128, 1841.0426578179272, 1871.8034606366407, 1888.9905550472436, 1894.507428068712, 1894.725264836591, 787.142015203431, 890.3500444675424, 918.8575770755543, 942.4345392764286, 1017.7404761785697, 17332.13339333028, 18397.26410943574, 18734.625307493774, 19100.2359905605, 21087.68368474474, 938.5213541094091, 955.7180631713661, 962.7063745557672, 1050.8108540462638, 1053.6536785758744, 9816.26378021637, 10225.897741022494, 11327.0, 11360.886391135982, 11398.772024559463, 490115.0, 580412.0979395076, 588712.0, 593519.5832375106, 597159.0, 96388.51805740927, 98871.0, 125729.34217960214, 131407.0, 142325.0, 422.6618705035959, 442.16210281516345, 444.1871375383041, 448.177594114803, 448.5514485514486, 9447.574859131493, 10035.046670566284, 10596.947015264874, 10675.839862401916, 10844.620438284623, 29655.81376744906, 48923.0, 53514.394568163014, 54718.726406367714, 54801.0, 53785.0, 75265.87101193596, 80130.59934700289, 80452.19547804681, 82654.0, 7673.386129109546, 7928.326092282221, 7929.960350198212, 8044.517328960281, 8090.555019473985, 11251.0, 12169.817452738393, 12242.143049986771, 12436.875631243935, 12476.750464990831, 10570.682879513626, 10762.3542587445, 10850.240483166102, 10909.127269818555, 10984.780304394028, 9475.47884866339, 10401.0, 10457.947710261704, 10570.312929659547, 10623.203259755395, 10909.781804364027, 10935.671929842118, 10995.615153469591, 11096.778064438828, 11152.665420037412, 6328.208973878265, 7905.6837726492595, 7961.243681850215, 7998.400119990907, 8160.0, 9623.807523849624, 10547.0, 10625.946870265909, 10785.676429707122, 10836.241463097507], "value": 9075.82024045751, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1496.6931778985308, 1527.0608575725928, 1761.6652835961168, 1897.1178402043047, 1917.8082191780823, 7982.201779822015, 8258.174182581739, 8392.454490458116, 8957.865632015524, 8982.236509896658, 8455.577221138949, 8769.60536775845, 8816.955915220431, 8905.15401036902, 8922.286217102632, 2345.64815277708, 2404.3989002749313, 2405.6391541268863, 2406.362313986795, 2407.5305315463493, 2351.694279743631, 2386.53462574798, 2389.940251493718, 2394.0543485323287, 2398.556267090585, 2370.158593699238, 2374.9050037998545, 2380.33350661815, 2383.1539803369815, 2406.4344878953434, 2362.9645555316683, 2371.92884213473, 2375.5842727522613, 2380.3811009137576, 2384.0463814474174, 2350.5298940212024, 2354.1407386304045, 2359.6696462495265, 2371.1819422299277, 2371.762823717632, 8772.254358379545, 8887.555622218928, 8934.061923498004, 8955.910440895637, 8963.596638151299, 5213.218017297398, 5358.732063396815, 5388.865278368072, 5391.703456309901, 5392.541633961078, 3321.186309354224, 3394.896658585945, 3534.7525673202886, 3606.3688854450365, 3626.564812222524, 948.966786162481, 987.6592575561419, 1045.163868904873, 1055.6094245129352, 1055.7888422315568, 11599.420028998516, 11614.535418583246, 11646.883531164665, 11709.004734597569, 11749.942505174575, 4359.367891655692, 4608.147492713859, 4617.953820461752, 4743.430788305425, 4849.442314133849, 9942.652007179713, 10008.899911000943, 10020.649277275406, 10024.699259022242, 10048.0, 4756.904861902812, 5223.660462070029, 5401.2168235606405, 5427.29445172133, 5434.456554344534, 4403.427554417905, 4554.931676024862, 4750.0, 4895.902081958412, 4942.604591632661, 3613.8735144269826, 3810.904727381838, 4011.8997025074023, 4046.5548789633117, 4055.6958228133008, 1293.4179619171223, 1300.5578103444875, 1304.8238487804215, 1314.3362601886176, 1315.4540865540694, 783.4789864739998, 858.7252079334678, 902.7833320003283, 904.5115637555778, 916.5966974531185, 5458.61789674719, 5597.272354593877, 5771.567132465082, 5846.239988801429, 5848.619839710405, 902.634433054614, 977.4721650308895, 1011.7774089700254, 1016.6594190946116, 1057.1912486947492, 3779.527559055118, 3792.867249646249, 3816.885493435201, 3892.377219644843, 3905.7070719695566, 9966.501674916226, 10055.491676248621, 10308.896911030944, 10715.410652414037, 10751.83872241917, 10701.89298107009, 10702.625408110678, 10703.83944240837, 10704.464776761131, 10819.729506762236, 7893.289603935674, 8116.878246826184, 8146.918530814734, 8265.710700125466, 8291.08798032216, 5538.224648549287, 5670.914936275876, 5677.77288908439, 5690.857728556819, 5806.622569532967, 9659.758506037406, 9852.211823053995, 9953.104220620178, 10010.499475026074, 10013.299069065095, 8571.614277357408, 8925.196732293865, 10219.182465402899, 10243.897561024294, 10265.58937642486, 6527.314631963575, 6739.932600674127, 6862.519623626498, 7046.823829404101, 7172.928270717226, 8302.377321700777, 8408.957955210184, 8477.0, 8559.486430814171, 8566.91433085661, 7222.205557388788, 7506.7372641959455, 7626.8855967159425, 7687.346575540918, 7741.303282704584, 7171.462140339391, 7296.671649775667, 7334.3032411920785, 7417.0, 7466.35740137169, 6847.760328388483, 6848.2466928638805, 6928.0, 6946.79159625212, 6962.721491140298, 4796.184648609675, 4852.0, 4870.366852309319, 4898.24077268024, 4946.678465899705, 4717.693349932243, 4814.711117332971, 4907.950920490749, 4987.877727511426, 4988.75056247179], "value": 4567.718739433141, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [271.5709179496396, 299.41016198089767, 305.7141572629591, 310.9409212249672, 314.5549048096943, 5020.673656212343, 5529.806456774014, 5562.276904002479, 5679.290088738908, 5712.371639119699, 4307.526172121072, 5506.6321481762825, 5584.52531534819, 5586.329640443152, 5606.971965140179, 3368.34317308125, 3728.906777330575, 4276.187524370367, 4303.870883873489, 4327.891802704926, 3945.684345252373, 4220.240356735786, 4314.180305741915, 4322.092360604277, 4325.913481730364, 4152.68854835887, 4175.728577642459, 4177.415161877339, 4185.539590645042, 4203.47456567929, 3668.4497325401135, 3812.4281357796553, 3861.0347413146715, 4164.771392439207, 4196.349565817303, 3197.2965947491516, 3223.88775872326, 3631.5823680276844, 3636.000099972529, 3638.9272214555826, 4578.0, 5028.6031547743105, 5427.3134798275105, 5434.212039254286, 5455.809046683347, 3846.576876543578, 4586.931196032062, 4838.540338667821, 4839.661223714342, 4844.5155484451625, 4686.742229177428, 4870.951290487121, 5063.0128224347445, 5368.70472124033, 5767.0772676372, 3507.719382449398, 3659.79871107089, 3724.703637280999, 3952.6047395260243, 3970.642642162219, 5077.466865979093, 5797.942020579824, 5962.807438512315, 6248.656323902183, 6297.811065668037, 4018.714011516345, 4021.778802165909, 4032.334664780278, 4035.414864844581, 4082.9387559186634, 3780.8109594520165, 4026.6577340925764, 4068.2270368629866, 4094.63148316647, 4094.7715685294293, 4152.1695660867945, 4733.834315798999, 4789.3055506952, 4796.448408433075, 4800.423949126058, 4886.828960986349, 5938.554608404387, 5984.760609575568, 5987.760709379293, 6009.519238461, 1298.8246586710673, 1312.3307113372248, 1359.8572149924116, 1369.5617402431126, 1393.9233342166278, 825.6490991328661, 853.833502467013, 853.9069990412282, 921.9812107621116, 933.6032186320922, 4495.57292057254, 4652.37166991557, 5435.673859568438, 5500.4224556420995, 5512.145617429305, 5956.086228861207, 6014.368491308437, 6016.42843929826, 6050.273967123973, 6051.394860513913, 4924.581410580069, 5477.835664930059, 5495.945040549624, 5496.862578435571, 5578.609497335228, 2829.7407653594364, 3142.277276226481, 3209.245827230599, 3210.5665735125926, 3212.506184624161, 5657.377688454267, 6137.7724455109155, 6280.246370435486, 6392.26488953767, 6419.646919419383, 4915.0, 5076.959223359203, 5313.388960269541, 6333.453266170959, 6452.870942581122, 5627.915581266284, 5734.6810233645665, 5966.4642526926755, 6010.9180347536985, 6478.963365861534, 5313.203019546944, 5626.442581508991, 5885.3820348862755, 5891.970540147272, 6173.98129308659, 5323.920141197963, 6126.081087836682, 6136.417040381157, 6183.721732521957, 6188.907166392418, 6069.757209711562, 6443.553947776019, 6468.2561505427475, 6472.708728107341, 6482.481401487776, 3387.9666701656233, 3876.972602260347, 3966.790129010564, 4039.333509970822, 4041.2321658884716, 5876.823695289148, 6225.719842607003, 6230.50155987529, 6239.812805615838, 6243.375662433902, 4451.866444006685, 4945.678530895624, 5118.9506151238165, 5305.363356397257, 5986.700664966821, 4902.975485122552, 5926.940730592639, 5928.377520360473, 5956.553258505716, 6038.849028774316, 4907.852764417074, 5576.498115169654, 5859.9121013183985, 5880.588358814842, 5938.099808061379, 6020.217371741645, 6071.0, 6081.118237855575, 6087.665178415053, 6102.542309326729, 5140.30605868203, 5349.679019258857, 5496.422875598165, 5591.455887571064, 5774.393688662798], "value": 4236.101281275848, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.8503014366983, 714.6248219684667, 715.8496715689705, 740.4631642059508, 828.1842385250529, 15265.694686106284, 16196.919015404936, 16198.947068440573, 16210.027398356107, 16239.106849123293, 14550.927245363784, 14568.053076550043, 14691.045082069659, 14750.33623486943, 14814.0, 866.086278975682, 879.1735768377737, 885.6590212768066, 886.171429713217, 889.1242126505387, 697.9651017449107, 720.6684924934536, 888.0586578227068, 891.7324802559207, 892.8214357128568, 906.2885634776702, 926.5182105305237, 936.9109934556207, 944.8535477001076, 946.4132238012442, 920.7790130368663, 925.1904583489446, 929.3076657889882, 931.2270815223383, 931.818295432391, 685.8045457044768, 693.4798900824381, 693.6531734132933, 694.9409300209488, 695.2699665351361, 13656.590302290948, 13708.862911370958, 13712.65718357049, 13724.519641812489, 13762.793558096637, 11440.94279528597, 13700.314984250748, 13758.380872860746, 13843.653908652264, 14205.502807401692, 12427.067969902204, 12482.750344993141, 12554.623361299176, 12568.270513634141, 12810.551630692975, 3880.2821478026653, 3916.7845768483007, 4423.933640995388, 4457.442819647544, 4533.659975501851, 113340.86659133386, 113566.86433135645, 113679.0, 114201.0, 116210.0, 9953.751156221153, 10506.804692339987, 10909.018188363085, 11002.614908478325, 11327.32036077838, 20079.698804517942, 21770.673439898415, 22002.119915203213, 22039.66940495894, 22068.33794986153, 7471.51435156724, 7812.984312039399, 7924.603769811486, 7970.521768693897, 8033.317168040782, 3909.7849618271266, 4016.3172260715746, 4308.870733877989, 4339.349097635317, 4456.598906098467, 3688.299223147647, 3823.0251285921913, 3829.5596006459054, 3834.0031591786337, 3839.0210496323816, 634.9460295874902, 691.2603514239815, 704.5490885833071, 743.5241445474901, 743.6690672650727, 556.3629644057531, 585.6370043178339, 606.6208619612742, 612.1429998002832, 650.6974256970436, 7260.600666963368, 9419.586239695636, 9579.329446938647, 9812.754681133028, 9846.852297215404, 578.2511647416605, 653.4543656047159, 672.5090683800853, 684.7979845945458, 692.2281655953676, 4652.302154676758, 4667.579917807414, 4718.764061796896, 4730.905381892343, 4803.0, 96698.51650741842, 136238.0, 152915.47084529235, 166197.0, 167674.0, 140425.0, 141216.0, 141299.29350353184, 142590.2870485641, 146506.0, 32847.0, 115275.0, 116095.0, 116924.0, 117625.4118729401, 8764.693235736719, 9067.954660226656, 9073.682421115229, 9093.77265568366, 9291.581878815334, 55875.441245587026, 65582.0, 65801.34198657951, 66943.66528167328, 67756.6612166936, 55935.88128237331, 56617.71691141518, 71452.0, 79760.0, 89984.1001590002, 13079.970857992716, 13197.80203296932, 13311.0, 13329.800052999406, 13406.932965335502, 13960.0, 14147.36336864823, 14291.857081429052, 14830.851691482947, 15529.611759706098, 12680.936595316964, 12792.488300467878, 12901.354932253538, 13224.471021159046, 13371.665708356979, 13256.0, 13482.52811151605, 13648.430430500222, 13715.451381944611, 13867.653308667364, 13474.0, 13598.0, 13734.587962361145, 13765.311734413439, 13849.307534623431, 9171.31215158879, 10696.946515267375, 11092.778144437227, 11347.773044539228, 11405.600803971822, 10787.298825576312, 11177.72055698614, 11401.087912967108, 11481.425928703698, 11777.823332650189], "value": 7819.661405198267, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1602.7000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 120.0, 162.0, 116.0, 117.0, 118.0, 116.0, 118.0, 118.0, 118.0], "value": 120.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 145.0, 145.0, 183.0, 119.0, 117.0, 119.0, 101.0, 174.0, 110.0], "value": 136.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [274.0, 210.0, 271.0, 275.0, 194.0, 199.0, 196.0, 201.0, 192.0, 196.0], "value": 220.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 61.0, 59.0, 83.0, 55.0, 57.0, 60.0, 60.0, 69.0, 52.0], "value": 63.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 101.0, 121.0, 82.0, 69.0, 69.0, 88.0, 70.0, 57.0, 97.0], "value": 85.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 89.0, 87.0, 91.0, 87.0, 90.0, 98.0, 118.0, 87.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 97.0, 131.0, 97.0, 94.0, 99.0, 154.0, 98.0, 152.0, 91.0], "value": 111.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [135.0, 136.0, 87.0, 71.0, 140.0, 113.0, 149.0, 101.0, 79.0, 142.0], "value": 115.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 57.0, 49.0, 52.0, 58.0, 50.0, 50.0, 72.0, 87.0], "value": 58.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 76.0, 70.0, 81.0, 73.0, 70.0, 70.0, 69.0, 70.0, 80.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 111.0, 93.0, 97.0, 108.0, 109.0, 81.0, 104.0, 112.0, 112.0], "value": 101.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 162.0, 163.0, 145.0, 146.0, 137.0, 156.0, 168.0, 212.0, 166.0], "value": 160.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 176.0, 173.0, 175.0, 175.0, 175.0, 199.0, 242.0, 200.0, 175.0], "value": 186.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 69.0, 69.0, 73.0, 71.0, 59.0, 75.0, 92.0, 91.0, 90.0], "value": 76.0, "unit": "score"}]}]} 10:01:22 INFO - Return code: 0 10:01:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:01:22 ERROR - # TBPL SUCCESS # 10:01:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:01:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:01:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:01:22 INFO - 2016-10-19 10:01:22.138 screenresolution[1669:10343] starting screenresolution argv=screenresolution get 10:01:22 INFO - 2016-10-19 10:01:22.141 screenresolution[1669:10343] Display 0: 1600x1200x32@60 10:01:22 INFO - 2016-10-19 10:01:22.147 screenresolution[1670:10346] starting screenresolution argv=screenresolution list 10:01:22 INFO - Available Modes on Display 0 10:01:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:01:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:01:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:01:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:01:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:01:22 INFO - Intel Iris: 10:01:22 INFO - Chipset Model: Intel Iris 10:01:22 INFO - Type: GPU 10:01:22 INFO - Bus: Built-In 10:01:22 INFO - VRAM (Dynamic, Max): 1536 MB 10:01:22 INFO - Vendor: Intel (0x8086) 10:01:22 INFO - Device ID: 0x0a2e 10:01:22 INFO - Revision ID: 0x0009 10:01:22 INFO - Displays: 10:01:22 INFO - Display: 10:01:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:01:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:01:22 INFO - Display Serial Number: 1600x1200 60 10:01:22 INFO - Main Display: Yes 10:01:22 INFO - Mirror: Off 10:01:22 INFO - Online: Yes 10:01:22 INFO - Rotation: Supported 10:01:22 INFO - Return code: 0 10:01:22 INFO - Running post-action listener: _resource_record_post_action 10:01:22 INFO - [mozharness: 2016-10-19 17:01:22.381163Z] Finished run-tests step (success) 10:01:22 INFO - Running post-run listener: _resource_record_post_run 10:01:22 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 38324736; Write bytes: 708865024; Read time: 239; Write time: 2014 10:01:22 INFO - TinderboxPrint: CPU usage
54.4% 10:01:22 INFO - TinderboxPrint: I/O read bytes / time
38,324,736 / 239 10:01:22 INFO - TinderboxPrint: I/O write bytes / time
708,865,024 / 2,014 10:01:22 INFO - TinderboxPrint: CPU idle
2,213.8 (45.6%) 10:01:22 INFO - TinderboxPrint: CPU system
979.9 (20.2%) 10:01:22 INFO - TinderboxPrint: CPU user
1,656.1 (34.1%) 10:01:22 INFO - TinderboxPrint: Swap in / out
445,001,728 / 0 10:01:22 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 5560320; Write bytes: 409229312; Read time: 46; Write time: 882 10:01:22 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 30011904; Write bytes: 298030080; Read time: 175; Write time: 1124 10:01:22 INFO - Running post-run listener: _upload_blobber_files 10:01:22 INFO - Blob upload gear active. 10:01:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:01:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:01:22 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:01:22 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:01:23 INFO - (blobuploader) - INFO - Open directory for files ... 10:01:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:01:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:01:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:01:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:24 INFO - (blobuploader) - INFO - Done attempting. 10:01:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:01:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:01:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:25 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:01:25 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:25 INFO - (blobuploader) - INFO - Done attempting. 10:01:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:01:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:01:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:01:27 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:01:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:01:27 INFO - (blobuploader) - INFO - Done attempting. 10:01:27 INFO - (blobuploader) - INFO - Iteration through files over. 10:01:27 INFO - Return code: 0 10:01:27 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:01:27 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:01:27 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23700e21a9899c2c7fa4a0d0b93c7f40c8585c0b90acdce93dd0b3a1c0c006c6d32b7420e08102b11af2a10a18bd8f8f0c0afab9e24517441a08d8e15e2faf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/617b8d42d31959011e29e996ded4d478813ae5ecbaa7694fbc14046f5e11a062cb439f4e66dc80df2f5c1e86d90ea66fe61d0004d0ca43d26615684bbe6df0b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a28325725e642ec52327aa66711881f35c8141799c4633ba710330c90eba0410da36e41af03133515578a1dcd50dd1fc742c1e44dbe3b4b85d775fa39c86690b"} 10:01:27 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:01:27 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:01:27 INFO - Contents: 10:01:27 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23700e21a9899c2c7fa4a0d0b93c7f40c8585c0b90acdce93dd0b3a1c0c006c6d32b7420e08102b11af2a10a18bd8f8f0c0afab9e24517441a08d8e15e2faf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/617b8d42d31959011e29e996ded4d478813ae5ecbaa7694fbc14046f5e11a062cb439f4e66dc80df2f5c1e86d90ea66fe61d0004d0ca43d26615684bbe6df0b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a28325725e642ec52327aa66711881f35c8141799c4633ba710330c90eba0410da36e41af03133515578a1dcd50dd1fc742c1e44dbe3b4b85d775fa39c86690b"} 10:01:27 INFO - Running post-run listener: copy_logs_to_upload_dir 10:01:27 INFO - Copying logs to upload dir... 10:01:27 INFO - mkdir: /builds/slave/test/build/upload/logs 10:01:27 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.921780 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-19 10:01:27.845666) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 10:01:27.849684) ========= 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.mjjDVVA2gH/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.YSLecAHNM1/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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/Fx-Team/sha512/23700e21a9899c2c7fa4a0d0b93c7f40c8585c0b90acdce93dd0b3a1c0c006c6d32b7420e08102b11af2a10a18bd8f8f0c0afab9e24517441a08d8e15e2faf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/617b8d42d31959011e29e996ded4d478813ae5ecbaa7694fbc14046f5e11a062cb439f4e66dc80df2f5c1e86d90ea66fe61d0004d0ca43d26615684bbe6df0b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a28325725e642ec52327aa66711881f35c8141799c4633ba710330c90eba0410da36e41af03133515578a1dcd50dd1fc742c1e44dbe3b4b85d775fa39c86690b"} build_url:https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010826 build_url: 'https://queue.taskcluster.net/v1/task/fWo59LFaQjWVRcL_U6nfSg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/23700e21a9899c2c7fa4a0d0b93c7f40c8585c0b90acdce93dd0b3a1c0c006c6d32b7420e08102b11af2a10a18bd8f8f0c0afab9e24517441a08d8e15e2faf92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/617b8d42d31959011e29e996ded4d478813ae5ecbaa7694fbc14046f5e11a062cb439f4e66dc80df2f5c1e86d90ea66fe61d0004d0ca43d26615684bbe6df0b6", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a28325725e642ec52327aa66711881f35c8141799c4633ba710330c90eba0410da36e41af03133515578a1dcd50dd1fc742c1e44dbe3b4b85d775fa39c86690b"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 10:01:27.892309) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 10:01:27.893045) ========= 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.mjjDVVA2gH/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.YSLecAHNM1/Listeners TMPDIR=/var/folders/dz/z373y_vn69q3ypv0dpprfcs400000w/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.004903 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 10:01:27.947183) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 10:01:27.949590) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 10:01:27.950056) ========= ========= Total master_lag: 0.20 =========