builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0061 starttime: 1476901357.79 results: success (0) buildid: 20161019075022 builduid: b23d5a1e38c84588ac677cc7a1168630 revision: 9f7b448a6b5ce5dfcffbcde6eed50052f3105a26 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.794790) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.795207) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.798602) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.829311) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.829898) ========= 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.UyltK14vOy/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.mrekMM8cRA/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:22:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 181M=0s 2016-10-19 11:22:37 (181 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.107132 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.963129) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:37.963437) ========= 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.UyltK14vOy/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.mrekMM8cRA/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.026355 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:38.012330) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-19 11:22:38.013900) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 9f7b448a6b5ce5dfcffbcde6eed50052f3105a26 --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 9f7b448a6b5ce5dfcffbcde6eed50052f3105a26 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.UyltK14vOy/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.mrekMM8cRA/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:22:38,087 truncating revision to first 12 chars 2016-10-19 11:22:38,087 Setting DEBUG logging. 2016-10-19 11:22:38,087 attempt 1/10 2016-10-19 11:22:38,087 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/9f7b448a6b5c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 11:22:40,180 unpacking tar archive at: fx-team-9f7b448a6b5c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.346661 ========= master_lag: 1.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-10-19 11:22:41.538067) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:41.538391) ========= script_repo_revision: 9f7b448a6b5ce5dfcffbcde6eed50052f3105a26 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:41.538795) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:41.539058) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 11:22:41.558351) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-19 11:22:41.558718) ========= /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.UyltK14vOy/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.mrekMM8cRA/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:22:41 INFO - MultiFileLogger online at 20161019 11:22:41 in /builds/slave/test 11:22:41 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 11:22:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:22:41 INFO - {'append_to_log': False, 11:22:41 INFO - 'base_work_dir': '/builds/slave/test', 11:22:41 INFO - 'blob_upload_branch': 'Fx-Team', 11:22:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:22:41 INFO - 'branch': 'Fx-Team', 11:22:41 INFO - 'buildbot_json_path': 'buildprops.json', 11:22:41 INFO - 'config_files': ('talos/mac_config.py',), 11:22:41 INFO - 'default_actions': ('clobber', 11:22:41 INFO - 'read-buildbot-config', 11:22:41 INFO - 'download-and-extract', 11:22:41 INFO - 'populate-webroot', 11:22:41 INFO - 'create-virtualenv', 11:22:41 INFO - 'install', 11:22:41 INFO - 'run-tests'), 11:22:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:22:41 INFO - 'download_minidump_stackwalk': True, 11:22:41 INFO - 'download_symbols': 'ondemand', 11:22:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:22:41 INFO - 'tooltool.py': '/tools/tooltool.py', 11:22:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:22:41 INFO - '/tools/misc-python/virtualenv.py')}, 11:22:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:22:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:22:41 INFO - 'installer_path': 'installer.exe', 11:22:41 INFO - 'log_level': 'info', 11:22:41 INFO - 'log_name': 'talos', 11:22:41 INFO - 'log_to_console': True, 11:22:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:22:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:22:41 INFO - 'opt_config_files': (), 11:22:41 INFO - 'pip_index': False, 11:22:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:22:41 INFO - 'cmd': ('bash', 11:22:41 INFO - '-c', 11:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:22:41 INFO - 'enabled': True, 11:22:41 INFO - 'halt_on_failure': False, 11:22:41 INFO - 'name': 'check_screen_resolution'},), 11:22:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:22:41 INFO - 'cmd': ('bash', 11:22:41 INFO - '-c', 11:22:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:22:41 INFO - 'enabled': True, 11:22:41 INFO - 'halt_on_failure': False, 11:22:41 INFO - 'name': 'check_screen_resolution'},), 11:22:41 INFO - 'run_cmd_checks_enabled': True, 11:22:41 INFO - 'sps_profile': False, 11:22:41 INFO - 'sps_profile_interval': 0, 11:22:41 INFO - 'suite': 'dromaeojs-e10s', 11:22:41 INFO - 'system_bits': '32', 11:22:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:22:41 INFO - 'title': 't-yosemite-r7-0061', 11:22:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:22:41 INFO - 'use_talos_json': True, 11:22:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:22:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:22:41 INFO - 'work_dir': 'build'} 11:22:41 INFO - [mozharness: 2016-10-19 18:22:41.767626Z] Running clobber step. 11:22:41 INFO - Running pre-action listener: _resource_record_pre_action 11:22:41 INFO - Running main action method: clobber 11:22:41 INFO - rmtree: /builds/slave/test/build 11:22:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:22:43 INFO - Running post-action listener: _resource_record_post_action 11:22:43 INFO - [mozharness: 2016-10-19 18:22:43.990114Z] Finished clobber step (success) 11:22:43 INFO - [mozharness: 2016-10-19 18:22:43.990246Z] Running read-buildbot-config step. 11:22:43 INFO - Running pre-action listener: _resource_record_pre_action 11:22:43 INFO - Running main action method: read_buildbot_config 11:22:43 INFO - Using buildbot properties: 11:22:43 INFO - { 11:22:43 INFO - "project": "", 11:22:43 INFO - "product": "firefox", 11:22:43 INFO - "script_repo_revision": "production", 11:22:43 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 11:22:43 INFO - "repository": "", 11:22:43 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 11:22:43 INFO - "buildid": "20161019075022", 11:22:43 INFO - "pgo_build": "False", 11:22:43 INFO - "basedir": "/builds/slave/test", 11:22:43 INFO - "buildnumber": 5, 11:22:43 INFO - "slavename": "t-yosemite-r7-0061", 11:22:43 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:22:43 INFO - "platform": "yosemite_r7", 11:22:43 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 11:22:43 INFO - "branch": "fx-team", 11:22:43 INFO - "repo_path": "integration/fx-team", 11:22:43 INFO - "revision": "9f7b448a6b5ce5dfcffbcde6eed50052f3105a26", 11:22:43 INFO - "stage_platform": "macosx64", 11:22:43 INFO - "builduid": "b23d5a1e38c84588ac677cc7a1168630", 11:22:43 INFO - "slavebuilddir": "test" 11:22:43 INFO - } 11:22:43 INFO - Found installer url https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 11:22:43 INFO - Running post-action listener: _resource_record_post_action 11:22:43 INFO - [mozharness: 2016-10-19 18:22:43.993455Z] Finished read-buildbot-config step (success) 11:22:43 INFO - [mozharness: 2016-10-19 18:22:43.993559Z] Running download-and-extract step. 11:22:43 INFO - Running pre-action listener: _resource_record_pre_action 11:22:43 INFO - Running main action method: download_and_extract 11:22:43 INFO - mkdir: /builds/slave/test/build/tests 11:22:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:22:43 INFO - trying https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 11:22:43 INFO - Downloading https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/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 11:22:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/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 11:22:44 INFO - Downloaded 1199 bytes. 11:22:44 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 11:22:44 INFO - Using the following test package requirements: 11:22:44 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:22:44 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 11:22:44 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 11:22:44 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'jsshell-mac.zip'], 11:22:44 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 11:22:44 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 11:22:44 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 11:22:44 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 11:22:44 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 11:22:44 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 11:22:44 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 11:22:44 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:22:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:22:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:22:44 INFO - Fetch https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:22:45 INFO - Http code: 200 11:22:45 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 11:22:45 INFO - x-amz-cf-id: M6l2zEhmcH54Ff1tWhAQbqjVtSlT9uVGWLl1f_rTZRF0fqVCWpw4Tw== 11:22:45 INFO - x-amz-version-id: gs0fo8PNeDBKv6p69_bFoxtl5nhC_qay 11:22:45 INFO - Expected file size: 16499981 11:22:45 INFO - Obtained file size: 16499981 11:22:52 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 11:22:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 11:22:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:22:52 INFO - Fetch https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 11:22:53 INFO - Http code: 200 11:22:53 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 11:22:53 INFO - x-amz-cf-id: 1mzMCtGbQJxPRPdPFBzmwDWVoMbLs868JDhUt3EoGD33hWVwgnBmDg== 11:22:53 INFO - x-amz-version-id: gs0fo8PNeDBKv6p69_bFoxtl5nhC_qay 11:22:53 INFO - Expected file size: 16499981 11:22:53 INFO - Obtained file size: 16499981 11:22:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 11:22:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:22:59 INFO - Fetch https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 11:23:00 INFO - Http code: 200 11:23:00 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 11:23:00 INFO - x-amz-cf-id: DErQAKqJQDCNcy7Ti41G5F8VYHRHXbfnHmJCd58xo1SnMWpuQRwtZA== 11:23:00 INFO - x-amz-version-id: oi4uWxBESm1wF6kZnUtOvObUyFwiLhoG 11:23:01 INFO - Expected file size: 14409028 11:23:01 INFO - Obtained file size: 14409028 11:23: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')]} 11:23:01 INFO - trying https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:23:01 INFO - Downloading https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 11:23:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/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 11:23:04 INFO - Downloaded 111645983 bytes. 11:23:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:23:04 INFO - mkdir: /builds/slave/test/properties 11:23:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:23:04 INFO - Writing to file /builds/slave/test/properties/build_url 11:23:04 INFO - Contents: 11:23:04 INFO - build_url:https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 11:23:04 INFO - Running post-action listener: _resource_record_post_action 11:23:04 INFO - Running post-action listener: set_extra_try_arguments 11:23:04 INFO - [mozharness: 2016-10-19 18:23:04.860568Z] Finished download-and-extract step (success) 11:23:04 INFO - [mozharness: 2016-10-19 18:23:04.860713Z] Running populate-webroot step. 11:23:04 INFO - Running pre-action listener: _resource_record_pre_action 11:23:04 INFO - Running main action method: populate_webroot 11:23:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:23:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:23:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:23:04 INFO - '--tppagecycles', 11:23:04 INFO - '7'], 11:23:04 INFO - 'tests': ['tsvgm']}}, 11:23:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:23:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:23:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:23:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:23:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:23:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:23:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['damp', 'tps']}, 11:23:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'tests': ['damp', 'tps']}, 11:23:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['dromaeo_dom']}, 11:23:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:23:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['basic_compositor_video']}, 11:23:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:23:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['a11yr', 11:23:04 INFO - 'ts_paint', 11:23:04 INFO - 'tpaint', 11:23:04 INFO - 'sessionrestore', 11:23:04 INFO - 'sessionrestore_no_auto_restore', 11:23:04 INFO - 'tabpaint']}, 11:23:04 INFO - 'other-e10s': {'tests': ['a11yr', 11:23:04 INFO - 'ts_paint', 11:23:04 INFO - 'tpaint', 11:23:04 INFO - 'sessionrestore', 11:23:04 INFO - 'sessionrestore_no_auto_restore', 11:23:04 INFO - 'tabpaint']}, 11:23:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:23:04 INFO - 'ts_paint', 11:23:04 INFO - 'tpaint', 11:23:04 INFO - 'sessionrestore', 11:23:04 INFO - 'sessionrestore_no_auto_restore', 11:23:04 INFO - 'tabpaint']}, 11:23:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:23:04 INFO - 'ts_paint', 11:23:04 INFO - 'tpaint', 11:23:04 INFO - 'sessionrestore', 11:23:04 INFO - 'sessionrestore_no_auto_restore', 11:23:04 INFO - 'tabpaint']}, 11:23:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['a11yr', 11:23:04 INFO - 'ts_paint', 11:23:04 INFO - 'tpaint', 11:23:04 INFO - 'sessionrestore', 11:23:04 INFO - 'sessionrestore_no_auto_restore', 11:23:04 INFO - 'tabpaint']}, 11:23:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['a11yr', 11:23:04 INFO - 'ts_paint', 11:23:04 INFO - 'tpaint', 11:23:04 INFO - 'sessionrestore', 11:23:04 INFO - 'sessionrestore_no_auto_restore', 11:23:04 INFO - 'tabpaint']}, 11:23:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['tsvgx', 11:23:04 INFO - 'tsvgr_opacity', 11:23:04 INFO - 'tart', 11:23:04 INFO - 'tscrollx', 11:23:04 INFO - 'cart']}, 11:23:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:23:04 INFO - 'tsvgr_opacity', 11:23:04 INFO - 'tart', 11:23:04 INFO - 'tscrollx', 11:23:04 INFO - 'cart']}, 11:23:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'talos_options': ['--disable-e10s'], 11:23:04 INFO - 'tests': ['tp5o']}, 11:23:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'tests': ['tp5o']}, 11:23:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'talos_options': ['--disable-e10s', 11:23:04 INFO - '--xperf_path', 11:23:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:23:04 INFO - 'tests': ['tp5n']}, 11:23:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:23:04 INFO - 'talos_options': ['--xperf_path', 11:23:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:23:04 INFO - 'tests': ['tp5n']}}, 11:23:04 INFO - 'talos.zip': {'path': '', 11:23:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:23:04 INFO - Running post-action listener: _resource_record_post_action 11:23:04 INFO - [mozharness: 2016-10-19 18:23:04.879127Z] Finished populate-webroot step (success) 11:23:04 INFO - [mozharness: 2016-10-19 18:23:04.879210Z] Running create-virtualenv step. 11:23:04 INFO - Running pre-action listener: _resource_record_pre_action 11:23:04 INFO - Running main action method: create_virtualenv 11:23:04 INFO - Creating virtualenv /builds/slave/test/build/venv 11:23:04 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 11:23:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:23:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:23:05 INFO - Using real prefix '/tools/python27' 11:23:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:23:05 INFO - Installing distribute.............................................................................................................................................................................................done. 11:23:08 INFO - Installing pip.................done. 11:23:08 INFO - Return code: 0 11:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 11:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 11:23:08 INFO - Reading from file tmpfile_stdout 11:23:08 INFO - Output received: 11:23:08 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) 11:23:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:23: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')]} 11:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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')]} 11:23:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23: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', 'pip>=1.5'] in /builds/slave/test/build 11:23: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 pip>=1.5 11:23:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:08 INFO - 'HOME': '/Users/cltbld', 11:23:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:08 INFO - 'LOGNAME': 'cltbld', 11:23:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:08 INFO - 'MOZ_NO_REMOTE': '1', 11:23:08 INFO - 'NO_EM_RESTART': '1', 11:23:08 INFO - 'PAGER': '/bin/cat', 11:23:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:08 INFO - 'PWD': '/builds/slave/test', 11:23:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:08 INFO - 'SHELL': '/bin/bash', 11:23:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:08 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:08 INFO - 'USER': 'cltbld', 11:23:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:08 INFO - 'XPC_FLAGS': '0x0', 11:23:08 INFO - 'XPC_SERVICE_NAME': '0', 11:23:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:09 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 11:23:09 INFO - Cleaning up... 11:23:09 INFO - Return code: 0 11:23:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:23:09 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')]} 11:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:09 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')]} 11:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:09 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:09 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 11:23:09 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 11:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:09 INFO - 'HOME': '/Users/cltbld', 11:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:09 INFO - 'LOGNAME': 'cltbld', 11:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:09 INFO - 'MOZ_NO_REMOTE': '1', 11:23:09 INFO - 'NO_EM_RESTART': '1', 11:23:09 INFO - 'PAGER': '/bin/cat', 11:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:09 INFO - 'PWD': '/builds/slave/test', 11:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:09 INFO - 'SHELL': '/bin/bash', 11:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:09 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:09 INFO - 'USER': 'cltbld', 11:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:09 INFO - 'XPC_FLAGS': '0x0', 11:23:09 INFO - 'XPC_SERVICE_NAME': '0', 11:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:09 INFO - Downloading/unpacking psutil>=3.1.1 11:23:09 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 11:23:09 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 11:23:09 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 11:23:09 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 11:23:09 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 11:23:09 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 11:23:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:23:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:23:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:23:12 INFO - Installing collected packages: psutil 11:23:12 INFO - Running setup.py install for psutil 11:23:12 INFO - building 'psutil._psutil_osx' extension 11:23:12 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 11:23:13 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 11:23:13 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 11:23:13 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 11:23:13 INFO - building 'psutil._psutil_posix' extension 11:23:13 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 11:23:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:23:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:23:13 INFO - ^ 11:23:13 INFO - 1 warning generated. 11:23:13 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 11:23:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:23:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:23:14 INFO - Successfully installed psutil 11:23:14 INFO - Cleaning up... 11:23:14 INFO - Return code: 0 11:23:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:14 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:14 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:14 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 11:23:14 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 11:23:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:14 INFO - 'HOME': '/Users/cltbld', 11:23:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:14 INFO - 'LOGNAME': 'cltbld', 11:23:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:14 INFO - 'MOZ_NO_REMOTE': '1', 11:23:14 INFO - 'NO_EM_RESTART': '1', 11:23:14 INFO - 'PAGER': '/bin/cat', 11:23:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:14 INFO - 'PWD': '/builds/slave/test', 11:23:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:14 INFO - 'SHELL': '/bin/bash', 11:23:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:14 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:14 INFO - 'USER': 'cltbld', 11:23:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:14 INFO - 'XPC_FLAGS': '0x0', 11:23:14 INFO - 'XPC_SERVICE_NAME': '0', 11:23:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:23:14 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 11:23:14 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 11:23:14 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 11:23:14 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 11:23:14 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 11:23:14 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 11:23:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:23:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:23:16 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:23:16 INFO - Installing collected packages: mozsystemmonitor 11:23:16 INFO - Running setup.py install for mozsystemmonitor 11:23:17 INFO - Successfully installed mozsystemmonitor 11:23:17 INFO - Cleaning up... 11:23:17 INFO - Return code: 0 11:23:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:23:17 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')]} 11:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:17 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')]} 11:23:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:17 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:17 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 11:23:17 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 11:23:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:17 INFO - 'HOME': '/Users/cltbld', 11:23:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:17 INFO - 'LOGNAME': 'cltbld', 11:23:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:17 INFO - 'MOZ_NO_REMOTE': '1', 11:23:17 INFO - 'NO_EM_RESTART': '1', 11:23:17 INFO - 'PAGER': '/bin/cat', 11:23:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:17 INFO - 'PWD': '/builds/slave/test', 11:23:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:17 INFO - 'SHELL': '/bin/bash', 11:23:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:17 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:17 INFO - 'USER': 'cltbld', 11:23:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:17 INFO - 'XPC_FLAGS': '0x0', 11:23:17 INFO - 'XPC_SERVICE_NAME': '0', 11:23:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:17 INFO - Downloading/unpacking jsonschema==2.5.1 11:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:19 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:23:19 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:23:19 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 11:23:19 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 11:23:19 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 11:23:19 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 11:23:19 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 11:23:19 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 11:23:20 INFO - Downloading functools32-3.2.3-2.tar.gz 11:23:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:23:20 INFO - warning: no files found matching '*.txt' 11:23:20 INFO - no previously-included directories found matching 'build' 11:23:20 INFO - no previously-included directories found matching 'dist' 11:23:20 INFO - no previously-included directories found matching '.git*' 11:23:20 INFO - Installing collected packages: jsonschema, functools32 11:23:20 INFO - Running setup.py install for functools32 11:23:20 INFO - warning: no files found matching '*.txt' 11:23:20 INFO - no previously-included directories found matching 'build' 11:23:20 INFO - no previously-included directories found matching 'dist' 11:23:20 INFO - no previously-included directories found matching '.git*' 11:23:20 INFO - Successfully installed jsonschema functools32 11:23:20 INFO - Cleaning up... 11:23:20 INFO - Return code: 0 11:23:20 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:20 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:20 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 11:23:20 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 11:23:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:20 INFO - 'HOME': '/Users/cltbld', 11:23:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:20 INFO - 'LOGNAME': 'cltbld', 11:23:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:20 INFO - 'MOZ_NO_REMOTE': '1', 11:23:20 INFO - 'NO_EM_RESTART': '1', 11:23:20 INFO - 'PAGER': '/bin/cat', 11:23:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:20 INFO - 'PWD': '/builds/slave/test', 11:23:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:20 INFO - 'SHELL': '/bin/bash', 11:23:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:20 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:20 INFO - 'USER': 'cltbld', 11:23:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:20 INFO - 'XPC_FLAGS': '0x0', 11:23:20 INFO - 'XPC_SERVICE_NAME': '0', 11:23:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:21 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:23:21 INFO - Cleaning up... 11:23:21 INFO - Return code: 0 11:23:21 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:23:21 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')]} 11:23:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:21 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')]} 11:23:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:21 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:21 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 11:23:21 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 11:23:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:21 INFO - 'HOME': '/Users/cltbld', 11:23:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:21 INFO - 'LOGNAME': 'cltbld', 11:23:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:21 INFO - 'MOZ_NO_REMOTE': '1', 11:23:21 INFO - 'NO_EM_RESTART': '1', 11:23:21 INFO - 'PAGER': '/bin/cat', 11:23:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:21 INFO - 'PWD': '/builds/slave/test', 11:23:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:21 INFO - 'SHELL': '/bin/bash', 11:23:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:21 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:21 INFO - 'USER': 'cltbld', 11:23:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:21 INFO - 'XPC_FLAGS': '0x0', 11:23:21 INFO - 'XPC_SERVICE_NAME': '0', 11:23:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:21 INFO - Downloading/unpacking blobuploader==1.2.4 11:23:21 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 11:23:21 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 11:23:21 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 11:23:21 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 11:23:21 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 11:23:21 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 11:23:23 INFO - Downloading blobuploader-1.2.4.tar.gz 11:23:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:23:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:23:23 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 11:23:23 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 11:23:23 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 11:23:23 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 11:23:23 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 11:23:23 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 11:23:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:23:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:23:24 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 11:23:24 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 11:23:24 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 11:23:24 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 11:23:24 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 11:23:24 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 11:23:25 INFO - Downloading docopt-0.6.1.tar.gz 11:23:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:23:25 INFO - Installing collected packages: blobuploader, requests, docopt 11:23:25 INFO - Running setup.py install for blobuploader 11:23:25 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:23:25 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:23:25 INFO - Running setup.py install for requests 11:23:25 INFO - Running setup.py install for docopt 11:23:25 INFO - Successfully installed blobuploader requests docopt 11:23:25 INFO - Cleaning up... 11:23:25 INFO - Return code: 0 11:23:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:25 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:25 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 11:23:25 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 11:23:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:25 INFO - 'HOME': '/Users/cltbld', 11:23:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:25 INFO - 'LOGNAME': 'cltbld', 11:23:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:25 INFO - 'MOZ_NO_REMOTE': '1', 11:23:25 INFO - 'NO_EM_RESTART': '1', 11:23:25 INFO - 'PAGER': '/bin/cat', 11:23:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:25 INFO - 'PWD': '/builds/slave/test', 11:23:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:25 INFO - 'SHELL': '/bin/bash', 11:23:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:25 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:25 INFO - 'USER': 'cltbld', 11:23:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:25 INFO - 'XPC_FLAGS': '0x0', 11:23:25 INFO - 'XPC_SERVICE_NAME': '0', 11:23:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-PpSJf3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-I5Isnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-IsaJmV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-H5elOh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-gihLfw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-tlcE_N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-QL85_c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:26 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-2dCMbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-H3LJC8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-zTf8sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-yiZMYp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-AhXMO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9AIA84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-WIPtyL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-I7axT3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-zT1gPe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:27 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-gfXbo5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:23:27 INFO - Running setup.py install for manifestparser 11:23:28 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:23:28 INFO - Running setup.py install for mozcrash 11:23:28 INFO - Running setup.py install for mozdebug 11:23:28 INFO - Running setup.py install for mozdevice 11:23:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:23:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:23:28 INFO - Running setup.py install for mozfile 11:23:28 INFO - Running setup.py install for mozhttpd 11:23:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:23:28 INFO - Running setup.py install for mozinfo 11:23:29 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Running setup.py install for mozInstall 11:23:29 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Running setup.py install for mozleak 11:23:29 INFO - Running setup.py install for mozlog 11:23:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Running setup.py install for moznetwork 11:23:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Running setup.py install for mozprocess 11:23:29 INFO - Running setup.py install for mozprofile 11:23:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:23:29 INFO - Running setup.py install for mozrunner 11:23:30 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:23:30 INFO - Running setup.py install for mozscreenshot 11:23:30 INFO - Running setup.py install for moztest 11:23:30 INFO - Running setup.py install for mozversion 11:23:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:23:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:23:30 INFO - Cleaning up... 11:23:30 INFO - Return code: 0 11:23:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:30 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:30 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 11:23:30 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 11:23:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:30 INFO - 'HOME': '/Users/cltbld', 11:23:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:30 INFO - 'LOGNAME': 'cltbld', 11:23:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:30 INFO - 'MOZ_NO_REMOTE': '1', 11:23:30 INFO - 'NO_EM_RESTART': '1', 11:23:30 INFO - 'PAGER': '/bin/cat', 11:23:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:30 INFO - 'PWD': '/builds/slave/test', 11:23:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:30 INFO - 'SHELL': '/bin/bash', 11:23:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:30 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:30 INFO - 'USER': 'cltbld', 11:23:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:30 INFO - 'XPC_FLAGS': '0x0', 11:23:30 INFO - 'XPC_SERVICE_NAME': '0', 11:23:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:23:30 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-etNDjx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:23:30 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)) 11:23:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:23:30 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-BSDflp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-JPdMdU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-9s2jFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-KMkktN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-q9kvUu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-4igZA3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-sEBJkc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-uKx0Vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-8iCINs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:23:31 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)) 11:23:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:23:31 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-Qbhbv4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:23:32 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)) 11:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:23:32 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-oARgrd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:23:32 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)) 11:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:23:32 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-2N991D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:23:32 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)) 11:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:23:32 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-zP2ZiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:23:32 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)) 11:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:32 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-WFDy0E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:23:32 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)) 11:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:23:32 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-GxMXP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:23:32 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)) 11:23:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:23:32 INFO - Running setup.py (path:/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/pip-bePc29-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:23:32 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)) 11:23:32 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)) 11:23:32 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)) 11:23:32 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)) 11:23:32 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)) 11:23:32 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)) 11:23:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:23:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:23:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:23:35 INFO - Downloading blessings-1.6.tar.gz 11:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:23:35 INFO - Installing collected packages: blessings 11:23:35 INFO - Running setup.py install for blessings 11:23:35 INFO - Successfully installed blessings 11:23:35 INFO - Cleaning up... 11:23:35 INFO - Return code: 0 11:23:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:23:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:35 INFO - Reading from file tmpfile_stdout 11:23:35 INFO - Current package versions: 11:23:35 INFO - blessings == 1.6 11:23:35 INFO - blobuploader == 1.2.4 11:23:35 INFO - docopt == 0.6.1 11:23:35 INFO - functools32 == 3.2.3-2 11:23:35 INFO - jsonschema == 2.5.1 11:23:35 INFO - manifestparser == 1.1 11:23:35 INFO - mozInstall == 1.12 11:23:35 INFO - mozcrash == 0.17 11:23:35 INFO - mozdebug == 0.1 11:23:35 INFO - mozdevice == 0.48 11:23:35 INFO - mozfile == 1.2 11:23:35 INFO - mozhttpd == 0.7 11:23:35 INFO - mozinfo == 0.9 11:23:35 INFO - mozleak == 0.1 11:23:35 INFO - mozlog == 3.4 11:23:35 INFO - moznetwork == 0.27 11:23:35 INFO - mozprocess == 0.23 11:23:35 INFO - mozprofile == 0.28 11:23:35 INFO - mozrunner == 6.12 11:23:35 INFO - mozscreenshot == 0.1 11:23:35 INFO - mozsystemmonitor == 0.3 11:23:35 INFO - moztest == 0.7 11:23:35 INFO - mozversion == 1.4 11:23:35 INFO - psutil == 3.1.1 11:23:35 INFO - requests == 1.2.3 11:23:35 INFO - wsgiref == 0.1.2 11:23:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:23:35 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')]} 11:23:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:35 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')]} 11:23:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:36 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 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:36 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 11:23:36 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 11:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:36 INFO - 'HOME': '/Users/cltbld', 11:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:36 INFO - 'LOGNAME': 'cltbld', 11:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:36 INFO - 'MOZ_NO_REMOTE': '1', 11:23:36 INFO - 'NO_EM_RESTART': '1', 11:23:36 INFO - 'PAGER': '/bin/cat', 11:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:36 INFO - 'PWD': '/builds/slave/test', 11:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:36 INFO - 'SHELL': '/bin/bash', 11:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:36 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:36 INFO - 'USER': 'cltbld', 11:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:36 INFO - 'XPC_FLAGS': '0x0', 11:23:36 INFO - 'XPC_SERVICE_NAME': '0', 11:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 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)) 11:23:36 INFO - Cleaning up... 11:23:36 INFO - Return code: 0 11:23:36 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1038d9490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10384f570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb3ead9e410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, '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.UyltK14vOy/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.mrekMM8cRA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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 11:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:23:36 INFO - 'HOME': '/Users/cltbld', 11:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:23:36 INFO - 'LOGNAME': 'cltbld', 11:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:23:36 INFO - 'MOZ_NO_REMOTE': '1', 11:23:36 INFO - 'NO_EM_RESTART': '1', 11:23:36 INFO - 'PAGER': '/bin/cat', 11:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:23:36 INFO - 'PWD': '/builds/slave/test', 11:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:23:36 INFO - 'SHELL': '/bin/bash', 11:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:23:36 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:23:36 INFO - 'USER': 'cltbld', 11:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:23:36 INFO - 'XPC_FLAGS': '0x0', 11:23:36 INFO - 'XPC_SERVICE_NAME': '0', 11:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:23:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:23:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:23:37 INFO - Cleaning up... 11:23:37 INFO - Return code: 0 11:23:37 INFO - Running post-action listener: _resource_record_post_action 11:23:37 INFO - Running post-action listener: _start_resource_monitoring 11:23:37 INFO - Starting resource monitoring. 11:23:37 INFO - [mozharness: 2016-10-19 18:23:37.230631Z] Finished create-virtualenv step (success) 11:23:37 INFO - [mozharness: 2016-10-19 18:23:37.231634Z] Running install step. 11:23:37 INFO - Running pre-action listener: _resource_record_pre_action 11:23:37 INFO - Running main action method: install 11:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:23:37 INFO - Reading from file tmpfile_stdout 11:23:37 INFO - Detecting whether we're running mozinstall >=1.0... 11:23:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:23:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:23:37 INFO - Reading from file tmpfile_stdout 11:23:37 INFO - Output received: 11:23:37 INFO - Usage: mozinstall [options] installer 11:23:37 INFO - Options: 11:23:37 INFO - -h, --help show this help message and exit 11:23:37 INFO - -d DEST, --destination=DEST 11:23:37 INFO - Directory to install application into. [default: 11:23:37 INFO - "/builds/slave/test"] 11:23:37 INFO - --app=APP Application being installed. [default: firefox] 11:23:37 INFO - mkdir: /builds/slave/test/build/application 11:23:37 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'] 11:23:37 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 11:24:11 INFO - Reading from file tmpfile_stdout 11:24:11 INFO - Output received: 11:24:11 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:24:11 INFO - Running post-action listener: _resource_record_post_action 11:24:11 INFO - [mozharness: 2016-10-19 18:24:11.822098Z] Finished install step (success) 11:24:11 INFO - [mozharness: 2016-10-19 18:24:11.822235Z] Running run-tests step. 11:24:11 INFO - Running pre-action listener: _resource_record_pre_action 11:24:11 INFO - Running main action method: run_tests 11:24:11 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:24:11 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:24:11 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:24:11 INFO - 2016-10-19 11:24:11.836 screenresolution[1641:7967] starting screenresolution argv=screenresolution get 11:24:11 INFO - 2016-10-19 11:24:11.838 screenresolution[1641:7967] Display 0: 1600x1200x32@60 11:24:11 INFO - 2016-10-19 11:24:11.844 screenresolution[1642:7969] starting screenresolution argv=screenresolution list 11:24:11 INFO - Available Modes on Display 0 11:24:11 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:24:11 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:24:11 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:24:11 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:24:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:24:12 INFO - Intel Iris: 11:24:12 INFO - Chipset Model: Intel Iris 11:24:12 INFO - Type: GPU 11:24:12 INFO - Bus: Built-In 11:24:12 INFO - VRAM (Dynamic, Max): 1536 MB 11:24:12 INFO - Vendor: Intel (0x8086) 11:24:12 INFO - Device ID: 0x0a2e 11:24:12 INFO - Revision ID: 0x0009 11:24:12 INFO - Displays: 11:24:12 INFO - Display: 11:24:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:24:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:24:12 INFO - Display Serial Number: 1600x1200 60 11:24:12 INFO - Main Display: Yes 11:24:12 INFO - Mirror: Off 11:24:12 INFO - Online: Yes 11:24:12 INFO - Rotation: Supported 11:24:12 INFO - Return code: 0 11:24:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:24:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:24:12 INFO - Python 2.7.3 11:24:12 INFO - Return code: 0 11:24:12 INFO - grabbing minidump binary from tooltool 11:24:12 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')]} 11:24:12 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 0x1036edd30>, '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 0x103513db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1036e5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:24:12 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 11:24:12 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 11:24:12 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 11:24:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:24:12 INFO - Return code: 0 11:24:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:24:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:24:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:24:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:24:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:24:12 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/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 11:24:12 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/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0061 --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 11:24:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.UyltK14vOy/Render', 11:24:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:24:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:24:12 INFO - 'HOME': '/Users/cltbld', 11:24:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:24:12 INFO - 'LOGNAME': 'cltbld', 11:24:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:24:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:24:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:24:12 INFO - 'MOZ_NO_REMOTE': '1', 11:24:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:24:12 INFO - 'NO_EM_RESTART': '1', 11:24:12 INFO - 'PAGER': '/bin/cat', 11:24:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:24:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:24:12 INFO - 'PWD': '/builds/slave/test', 11:24:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:24:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:24:12 INFO - 'SHELL': '/bin/bash', 11:24:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.mrekMM8cRA/Listeners', 11:24:12 INFO - 'TMPDIR': '/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/', 11:24:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:24:12 INFO - 'USER': 'cltbld', 11:24:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:24:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:24:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:24:12 INFO - 'XPC_FLAGS': '0x0', 11:24:12 INFO - 'XPC_SERVICE_NAME': '0', 11:24:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:24:12 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/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0061', '--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 11:24:12 INFO - mozversion application_buildid: 20161019075022 11:24:12 INFO - mozversion application_changeset: 9f7b448a6b5ce5dfcffbcde6eed50052f3105a26 11:24:12 INFO - mozversion application_display_name: Nightly 11:24:12 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:24:12 INFO - mozversion application_name: Firefox 11:24:12 INFO - mozversion application_remotingname: firefox 11:24:12 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:24:12 INFO - mozversion application_vendor: Mozilla 11:24:12 INFO - mozversion application_version: 52.0a1 11:24:12 INFO - mozversion platform_buildid: 20161019075022 11:24:12 INFO - mozversion platform_changeset: 9f7b448a6b5ce5dfcffbcde6eed50052f3105a26 11:24:12 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:24:12 INFO - mozversion platform_version: 52.0a1 11:24:12 INFO - using testdate: 1476901452 11:24:12 INFO - actual date: 1476901452 11:24:12 INFO - starting webserver on 'localhost:49280' 11:24:12 INFO - SUITE-START | Running 2 tests 11:24:12 INFO - TEST-START | dromaeo_css 11:24:12 INFO - operating with platform_type : mac_ 11:24:12 INFO - Initialising browser for dromaeo_css test... 11:24:12 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpoZUlNp/profile http://localhost:49280/getInfo.html) 11:24:15 INFO - PROCESS | 1652 | 2016-10-19 11:24:15.407 plugin-container[1654:8134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3b, name = 'com.apple.tsm.portname' 11:24:15 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:15 INFO - PROCESS | 1652 | 2016-10-19 11:24:15.410 plugin-container[1654:8134] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.CFPasteboardClient' 11:24:15 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:15 INFO - PROCESS | 1652 | 2016-10-19 11:24:15.410 plugin-container[1654:8134] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:24:15 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 11:24:15 INFO - PROCESS | 1652 | colorDepth:24 11:24:15 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 11:24:15 INFO - PROCESS | 1652 | __metrics 11:24:15 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 11:24:16 INFO - TEST-INFO | 1652: exit 0 11:24:16 INFO - Browser initialized. 11:24:16 INFO - Running cycle 1/1 for dromaeo_css test... 11:24:16 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmpoZUlNp/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:24:18 INFO - PROCESS | 1656 | 2016-10-19 11:24:18.630 plugin-container[1657:8258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883b, name = 'com.apple.tsm.portname' 11:24:18 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:18 INFO - PROCESS | 1656 | 2016-10-19 11:24:18.630 plugin-container[1657:8258] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441b, name = 'com.apple.CFPasteboardClient' 11:24:18 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:24:18 INFO - PROCESS | 1656 | 2016-10-19 11:24:18.631 plugin-container[1657:8258] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:24:28 INFO - PROCESS | 1656 | RSS: Main: 237076480 11:24:28 INFO - PROCESS | 1656 | 11:25:17 INFO - PROCESS | 1656 | 1476901517854 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] 11:27:32 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49280/tests/dromaeo/cssquery-ext.html) 11:27:33 INFO - PROCESS | 1656 | RSS: Main: 250421248 11:27:33 INFO - PROCESS | 1656 | 11:30:35 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-ext.html (next: http://localhost:49280/tests/dromaeo/cssquery-jquery.html) 11:30:36 INFO - PROCESS | 1656 | RSS: Main: 262680576 11:30:36 INFO - PROCESS | 1656 | 11:33:38 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49280/tests/dromaeo/cssquery-mootools.html) 11:33:38 INFO - PROCESS | 1656 | RSS: Main: 269524992 11:33:38 INFO - PROCESS | 1656 | 11:36:40 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49280/tests/dromaeo/cssquery-prototype.html) 11:36:41 INFO - PROCESS | 1656 | RSS: Main: 269344768 11:36:41 INFO - PROCESS | 1656 | 11:39:44 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49280/tests/dromaeo/cssquery-yui.html) 11:39:44 INFO - PROCESS | 1656 | RSS: Main: 274579456 11:39:44 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | Cycle 1(1): loaded http://localhost:49280/tests/dromaeo/cssquery-yui.html (next: http://localhost:49280/tests/dromaeo/cssquery-dojo.html) 11:42:47 INFO - PROCESS | 1656 | RSS: Main: 277356544 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | __start_tp_report 11:42:47 INFO - PROCESS | 1656 | _x_x_mozilla_page_load 11:42:47 INFO - PROCESS | 1656 | _x_x_mozilla_page_load_details 11:42:47 INFO - PROCESS | 1656 | |i|pagename|runs| 11:42:47 INFO - PROCESS | 1656 | |0;dojo.html;3324.817135057572;3335;3359.2273777031273;3376.7805092668973;3390.254144088303;5517.862053448665;5575.163725441186;5585.385607583163;5587.3015873015875;5700.287464066992;5559;5676.914846277309;5683.289588801389;5711;5735.0250457422235;5132.384113906336;5174.586033117342;5218.712970786605;5246.816361427332;5296.867578310535;5253.6322457428;5262.473752624745;5283.8150664726745;5316.0962636351915;5357.866053348658;5106.5659418949435;5148.974255128738;5251.711155886463;5259.552938000236;5274.630775845692;5119.334486516766;5154.690718556861;5228.058949389147;5238.607104467142;5290.973545132289;5141.254518094893;5158.9484105158845;5179.922301165486;5197.272381866542;5267.28891599633;5505.779768809283;5543.639663421905;5709.514691251248;5726.656400615976;5742.7415766290615;6065.787697430569;6083.026715725463;6111.105587097678;6127.494965205055;6190.164327815777;6175.7529698811995;6175.84560385994;6189.535784816157;6214.378562143795;6233.78959835685;5778.479936805624;5781.988152073371;5800.197745306026;5813.563982701315;5855.355910849803;6101.277634935909;6122.816315510542;6146.78486252979;6148.538859585459;6163.3836616338;6049.697515124226;6109.541784366191;6112.205413296243;6118.969405152946;6139.84650383744;5026.919212369358;5402.972985135128;5497.615166938356;5639.407862174413;6159.3224745277985;6040.607360521552;6097.161734506633;6101.035369327992;6101.206843110367;6109.556495504338;5465.551628818366;5708.65748055118;6119.654885716942;6123.038605230386;6182.165407669907;4324.221640104748;4871.903821640102;4906.1414252505665;4922.212446008622;4986.725730084808;3420.350133474632;3684.9631503685155;4377.627901628397;4418.779061046935;4439.755813430293;5285.154375299933;5326.8014696692935;5576.079946808731;5684.289463817023;5777.248957635565;6102.277183976845;6107.249501910372;6156.199694039836;6160.845978850564;6183.474404675653;5536.944630553723;5761;5800.883982320414;5819.388964158787;5820.650760954356;5380.91928621071;5396.325459317585;5399.595030372738;5409.675419474844;5444.128939369682;6128.197586012462;6142.877142457126;6172.321044685171;6175.289841668266;6190.721417536131;6117.583823141572;6119.715820550657;6122.215193971364;6123.4961516426065;6143.757528502154;6295.118683384421;6305.306416294296;6310.305866354605;6319.936800631935;6349.6507692077375;5907.172995780508;6046.941785187662;6062.7271772769445;6091.360407157184;6155.138280640804;4798.856034318976;6104.125697713593;6132.661461321697;6133.457080270946;6165.691715414301;6098.207233059674;6114.174586430775;6126.754929802758;6133.417325354084;6135.509159267159;5128.282040514406;5826;5947.137665038632;6032.426919442646;6120.387961203844;5801.680907550125;5905.4685078343155;5968.850778730566;5974.581779275409;6043.848903777441;6020.729067192075;6042.969785151046;6049.274087109575;6054.7275372607455;6083;5771.653700777967;5844.883102337844;5893.204417403767;5906.438888305604;5953.613015154001 11:42:47 INFO - PROCESS | 1656 | |1;ext.html;475.8191887082908;478.23721164742267;506.9543741063305;523.8559396166054;563.8956792993298;31056;41563.79218103909;41681.3747793783;43838.34242486365;43845.561544384545;42819.57180428195;43255.78372108135;43385.78307108468;43395.91510212238;43442.13115737683;9449.102335278154;9656.710298691016;9664.468454235015;9667.226621870268;9673.516324183798;9529.666461673844;9610.423374597547;9617.278704097185;9633.61465541381;9642.662506812263;9576.47329396883;9585;9612.903870961305;9626.566804493814;9672.081152290557;9556.90443095567;9578.952105239465;9618.326717129803;9639.566219520138;9651.517424128766;9460.479673617949;9544.570494327772;9556.187724043395;9569.569369378325;9577.904220957771;40282;40655.593444065475;40680.59319406797;40733.59266407328;40824.79587602043;3436.329915666446;3591.748577599569;3722.236941427001;4087.0171246515274;4095.9385609215587;30627.387452251052;30682.23294417635;30740.2314942126;30978;31023.53464698031;3141.575887255214;3171.4767063434506;3185.7132858042887;3228.725558327545;3289.73682105431;378069.10965445;426398;466635;468549;469217;51390.715232119495;56483;58541;58989;59030.70484647549;62214.68892655508;62463.68768156221;62581.68709156425;62600.68699656473;62790.03494667999;13081.803772943413;15856.60358491047;15969.840301597067;16037;16042.197890105448;12393.256404615751;12426.813597796041;12509.874901251053;12592.181508201938;12701.491940322283;13138.4087716053;15535;16012.759808602881;16024.599385015235;16069;5966.254218222723;7004.2645522220455;7153.323101528359;7181.35367816899;7201.3158749918675;881.2421317666801;892.973210803664;980.122790102854;1063.5426766490484;1076.8653918260218;28933.71066289352;29453.410931781244;29817.850910745743;30148.84925575358;30292;984.699666601679;1076.2143635146388;1080.5191689698008;1099.433791597328;1158.901493373056;15226;15566.377344906077;15581.065136091871;15713.764293535605;16005.199740012953;626451.8677406583;642842;649618;650233;651263;56833.431665682816;57033;57481.42518574844;57554.42445575491;57921.131183031444;1207.6014915078003;1375.3467103125936;1432.7850822376727;1438.4318194313187;1444.5882923367;30129.548056779604;30320.84839575788;30938.381232375676;31302;31677;49787;50522.74738626283;50526.747366262935;50598.45810793356;50701.98596028132;564141;568188.3181168135;569376;569510.3720332512;571012;42930;43650.56349436465;43933;44052.89867753332;44202;46515;47399.52600473951;48054.27918581154;48217.27674084821;48721;38777.806110969264;39026;39281;39902.201955961296;40422.59577404188;33400;38381.80809095937;38509.807450962566;39705.80147099246;39774.60225397825;37455;37771.92254426033;38025.4296185563;38579;38699.6130038696;34075.97772066842;34648.65351346555;34849.954501365;35289.64710352966;35358;21753.238636647642;22975.540489190458;23776.57340559572;25642.8717856417;26142.34644133912 11:42:47 INFO - PROCESS | 1656 | |2;jquery.html;328.51707989255794;364.85405837664933;376.7362846007795;377.15517241379285;396.8055652730163;19319.903400482995;20149;20304.593908121828;20893.164273429084;21035;19971.301004464847;20225.089870955806;20600;20758.584828303425;20834.374968750963;4778.641601879855;5765.596408251414;5848.118956056034;5978.850528736794;6049.274087109553;5893.8821223575505;6005.729742161613;6027.969860150693;6028.367021462749;6039.275286965571;5678;5892.116182572627;5938.346781853993;5961.105834124873;5995.411216027735;5898.734556944926;5920.168921095779;5937.317208521011;5975.282966044059;5984.281886173643;5005.799768009275;5149.845504634867;5183.844484665466;5185.922211166836;5188.7146206958605;25345.74654253452;25391.111311104207;25421.8560164793;25456.563363945814;25849.707514624195;2231.475603233239;2251.549690061994;2426.116667082318;2674.598810178469;2969.752703864382;14882;15453.459128930546;15786.763198552031;16074.919625401799;16229.675406491804;1851.3427733154806;1872.26981477224;1888.8961107139103;1903.6097599991967;1914.5787926656114;114980;116754.41622791917;117259;117464;117892.99999999914;9102.90897091034;10444.791104178026;10588.047075763217;10731.302465339728;10970.670879873456;13738.175709457464;13765.174089554459;13783.379747911367;13791.862081379259;13799.310034498234;8275.337972962148;8524.27543658796;8556.101609331055;8685;8689.43661619811;8400;8494.193051660082;8574.657013719507;8687.39188256816;8738.169873861973;6583.9012414813815;6710;6711.463082953352;6732.2931092235585;6761.898571521431;1823.963520729578;1844.4558855137766;1859.5722983713822;1864.142494452567;1865.8414034807192;872.82543491302;883.412530667112;897.7486303834806;929.5259417697023;950.2208189284859;17364.95810251389;21311.78688213098;22190.334289971328;22193;22230.221942231943;938.9201917836924;965.1603105298295;991.5785791038924;1043.2175868098925;1052.8262836632023;11141;11688.883111168949;11821.290722556674;11842.467088981017;11900.821487677691;539450;542803;547876;561208;561322;104376.47811760893;107074;107504.38743418855;108716;108937;970.262600423669;990.4552496127101;998.4209158687944;998.4508520313943;1003.5433877585681;18103.637927241645;19120.713189301892;19305.710414343514;19306.227750889808;19407.2237110514;49845.75077124591;50535.74732126316;51160.74419627878;51557;51719.707007325116;41196.17607647771;64728;73619;73915.63042184754;74648;7739.3421559165845;7924.960375198087;8043.839123217386;8137.59312034384;8189.22202390772;12836.614901552968;12854.012011322484;13292.867071329163;13339.733205336033;13423.993200509805;10667.572278295698;11190.496427660937;11416.771664566828;11530.019948304489;11586.536538538365;10195.082442580204;10451.059404653617;11043.226974111734;11500.884991149982;11633.941830290794;9216.769580760534;10415.895841041493;10931.781364372828;11590.94204528972;11817.995470384769;7025.824354391181;7319.377852882564;7428.73999410018;7650.885236721342;7917.920820791718;9721;11213.271137376045;11593.6521904343;11674.007709344802;11744.236624619714 11:42:47 INFO - PROCESS | 1656 | |3;mootools.html;1521.8021657184568;1548.241361732751;1622.756586512023;1782.3494424535033;1785.3126546279686;6525.347465253484;7585.317321441069;8107.351411887043;8259.95870020649;9142.131497507735;8565.143485651432;8848.115188481148;8892.244159246457;8941.374103812735;8968.461892286467;2073.046398656616;2311.1564279038116;2388.080588973249;2390.988045059772;2393.102586530051;2361.2207971369407;2374.489484760776;2400.3879010852206;2403.483251101013;2404.591219492682;2350.4476448034698;2358.481134150484;2371.300466362419;2379.4170428245106;2389.892454839536;2048.2933387981125;2339.6724458575814;2342.4143964009;2358.8348815582917;2389.7968672662846;2372.9525409491885;2379.3337865397716;2380.4762952150672;2382.4996750682376;2384.6423036544484;8902.243309318716;8963.55182240892;8970.102989701043;9003.639854405881;9017.323700722472;5183.585313174937;5277.261183434322;5397.649152805055;5419.34967803866;5445;3046.2232130806724;3603.387424137903;3612.187257866985;3686.0416291764336;3686.889393318205;1022.7698767777265;1048.2557384257186;1058.2327812336073;1077.2782235901964;1080.3193987787722;11729.765404691858;11746.530138794524;11801.763964720743;11821.231619944676;11955.641330760003;4618.769061546976;4663.603593694506;4853.490383509751;4882.681675947545;4887.926681099786;9945.850812237823;10039.247056470795;10050.145737612238;10051.69844904654;10098.949505252427;5396.055690254189;5421;5422.593305502024;5431.429699881534;5440.4287549807495;4404.383386325917;4558.997020655451;4758.95241047592;4919.2867034279825;4940.629452791055;3912.374020156761;3914.784686842251;4009.6190861868076;4020.3768415895584;4054.8175332110127;1299.9405484530078;1316.2365827819842;1318.0444177970894;1325.0592079623575;1328.5283724277936;778.8442311537715;811.7564730580802;909.8953620333747;935.8596210568469;947.8056998315329;5120.206368012964;5509.944900551023;5608.775648974077;5768.538516918637;5863.355030946593;417.70551761008414;432.5263836099466;435.4187160141184;436.6288654643591;441.46803102261697;3306.834658267077;3776.5656949451163;3781.5840257571645;3835.6547910688173;3878.127421330178;10603.628872989408;10775.78448431027;10895.618653347095;10911.672649820519;10938.835917461245;7926.841463170819;9202.953985230031;10855.131589472823;10866.891331086747;10877.564897404016;6589.80230593083;8026.7591972240925;8326.6669333226;8331.750047498585;8359.456635318686;4601.723896566216;5528.115501519737;5609.046462101534;5611.158326251095;5771.422857714195;7893.644785984759;8348.165183481604;9363.719088427359;10097.798044039226;10113.747156321151;8777.385583009129;9574.377665452002;10001.249906257206;10033.899661003297;10228.53971571296;6905.343992320722;7112.933060041035;7468.477206595703;7651.770446886602;7961.243681850215;8157.265846073882;8388.035375931846;8413.368997325348;8481.321494280566;8500.489970601784;7395.075615548057;7465.365443937326;7475.626218689153;7499.887501687588;7530.924690753022;7267.7819665410125;7325.304096110861;7346.33882950537;7355.301246381585;7358.742444014434;6740.898886516804;6742.595444273359;6829.795106146824;6842.213145488134;6842.213145488333;4420.7126536775;4802.855914322576;4929.285253638274;4961.7271050092595;5011.649184557192;4800.879978000578;4867.537583929521;4923.876903077308;4963.081829861487;4972.5027497249985 11:42:47 INFO - PROCESS | 1656 | |4;prototype.html;299.47890670233784;302.6459042919784;309.3441903165289;311.94073126106025;313.85562641185055;5181.41444821333;5416.322959630046;5518.751656175472;5564.610477266593;5601.711606330541;4551.749653769041;5340.465953404658;5363.570914326854;5444.128939369702;5459.972700136494;3823.604384399688;4105.126411511938;4181.644560212386;4195.85314513992;4237.724547904377;3971.5035620547433;4181.014018341294;4224.493060832704;4232.788360581974;4253.361995700654;3343.882964096257;3794.753341032838;4144.41978123063;4151.239425781851;4171.228322760299;3856;4170.52039015514;4188.6649068074485;4220.556841531657;4230.111676547944;3486.546748922624;3602.5676918769655;3655.4334078217917;3658.432942893856;3659.524261845943;4020.7587544747407;4597.402337696078;5149.92309075291;5231.895362092775;5320.920186197211;4117.91764164715;4659.79030943605;4819.156647586658;4856.684315519516;4858.125537403266;3994.960050399517;5564.33228012637;5660.5471562274915;5666.46168613981;5858.53131749459;3708.9443658345144;3959.980200098981;4003.199360127957;4005.679545636342;4013.2776100301935;5238.554722848562;5242.528172464458;5635.971820140873;6061.605995610315;6140.324564297924;3638.9803658969;3762.5861155272896;3766.642168993941;4057.3102572562734;4095.8976025599004;3691.1141326082084;4141.420201171838;4149.937750933738;4153.003279213028;4162.4796900387455;3984.5019372578427;4779.354787103696;4797.520247975244;4832.830850920201;4833.468318484964;4702.835400760957;4776.593989510861;5570.597698304665;5736.42635736423;5809.390014048548;1171.5957994491887;1229.1457437081322;1371.300636675283;1410.6967002094393;1422.160925054223;759.9278068583476;813.3818298093491;848.8540470364958;880.3969281042379;881.3962435731545;3946.210757848442;4140.385249752599;4417.712848664891;4468.73187608738;4587;4188.267053265667;4970.577500912391;4976.527229913152;4980.427250866197;5016.498350164954;4194;4339.5443478434945;4442.111577684476;4523.909521809545;4591.586757191802;2094.863833850795;2389.593769059264;2470.7035155781423;2507.6740023797156;2605.8566778826967;4360.520342762294;4522.751248681354;4571.885702857455;4863.5166274286685;5236.486486486431;4543.114092751882;5045.84862454127;5070.949290507047;5162.638615296968;5206.01085793698;3871.8838434847;4442.48911375196;4578.03861189154;5144.890594856091;5182.149817600305;4587.788548016469;4767.261074533453;4894.779734912009;4979.825706100269;5006.524380183877;4259.97870010648;4904.583110435653;4948.2824990375475;4964.10646083694;4998.077278425078;4459.101583970445;4720.834770783006;4928.605711542997;5155.53600175986;5255.265762298356;3229.35193141631;3462.705670017976;3912.5891781362548;3969.2061587683156;4059.7564146151326;5325.893482130413;5369.677819330853;5660.782931669711;5888.735006924613;5905.822825315247;4515.9774201128785;5322.828977624917;5738.885222295614;5771.769129234952;5842.795502157404;4576.954230457653;5122.718250496258;5601.775928962796;5672.9505041567445;5729.828105156852;5118.436971933159;5160.870978225575;5232.1628539433505;5669.229962075174;5721.399253078367;3852.114013776791;4785.306130611111;5469.0155771959835;5771.2208851806145;5771.8442721240635;5569;5635.942880848543;5724.942750572441;5733.254676891977;5735.197072409783 11:42:47 INFO - PROCESS | 1656 | |5;yui.html;713.3259070178681;730.1566690472506;762.7711686494052;776.9106552746435;790.1111249843925;12890.935545322272;13097.410616522255;14984.850151498482;16430.260638271277;16559.66880662386;12267.63197104086;14835.406583736638;14912;14963.700725985474;15008.774868376955;861.4228466927158;878.5914549734372;880.3969281042474;880.7886107334226;886.4238245140646;881.030866047346;883.3904605821995;885.5395194498857;887.1704955866253;887.8002449448854;904.7647611620993;921.0789210789211;921.5162039929024;921.9631214751402;925.1765928323797;882.298572634757;908.1826356279402;911.4804561400005;916.1434059154678;922.2990527199377;655.5149189599709;680.2245440198178;683.3269229808634;683.3439897698215;686.1423220973783;12571.18287311322;13212.33938303081;13999.720005599831;14012.579622611338;14041.78937315941;11535.76928461426;11916.225445346025;12036.879631203663;12358.505659773598;12375.381230938507;10467.738306542322;10910.181736369805;10923.563057477691;11081.501332440059;11106.278091924;3663.523741913534;3696.3900956342172;3716.8141592920247;3846.961530384688;3862.6909847212155;111513;111788;112151;113923.43038284838;115744.42127789391;9186.219171370509;9247.72256832296;9268.675596354231;9417.293702972305;9624.03759624032;19626.803731962784;19803.801961980196;20049.899750501154;20054.197832086844;20057.39827805168;7751.263629955145;7763.990681211407;7815.726449574237;7817.726379576688;8037.477563958324;4016.5380981187536;4250.893727656771;4298;4309.439772829574;4440.045390241114;3523.5771707395274;3628.0385697790107;3637.3270944875285;3711.7958512281543;3886.9222615547533;613.871087071711;620.8634100497883;653.8201994451564;695.3255342317943;700.0758998122532;580.9477147056785;680.4590350671228;697.3375293471223;702.2415790945767;703;9241.676541321021;10092.596296148218;10138.949305253427;10216.387016779017;10365.792684146425;620.7655450449787;651.4560342114388;653.2128784814288;690.4648897104784;695.9247961898777;4177;4498.707584007029;4654.25531914892;4705.106029854317;4738.644601654821;86722;88113.55943220243;116676;140270;143614;82337;84829;91917.16165676649;123949;124117;105590.47204764081;106638;107042.92957070486;108465.83068338747;108530.99999999841;7580.279873412017;8925.285977121945;9136.853437534244;9145.679901203426;9159;42740.572594274905;48468.3036093735;54630;60930;62854.68572657107;88929;92534;94221.5288923551;94361.2612297331;95142;11441.913018263253;11659.242149260272;11923.582674606347;12004.819927701266;12208.32854193028;11248.94375528117;13077;13622.93188534051;13697.726045478836;13701.45194192261;11876;12120.454579543766;12208.877911221129;12363.0727695425;12508.312042837731;11916.761664766484;12111.031117510402;12483.812742808683;12503.187292825936;12589.874101259236;11785.764284714429;11882.108841836724;11946.462409191434;12360.938195309325;12368.938155309166;9481.810363792823;9645.855312170463;9761.511924403609;9777.462239576891;9978.60085596568;8784.429012114193;9175.311851611023;9562;9562.330636855353;10571.841422378824 11:42:47 INFO - PROCESS | 1656 | __end_tp_report 11:42:47 INFO - PROCESS | 1656 | __start_cc_report 11:42:47 INFO - PROCESS | 1656 | _x_x_mozilla_cycle_collect,360 11:42:47 INFO - PROCESS | 1656 | __end_cc_report 11:42:47 INFO - PROCESS | 1656 | __startTimestamp1476902567603__endTimestamp 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | ------- Summary: start ------- 11:42:47 INFO - PROCESS | 1656 | Number of tests: 6 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | [#0] dojo.html Cycles:165 Average:5645.85 Median:5819.39 stddev:645.12 (11.1%) stddev-sans-first:620.87 11:42:47 INFO - PROCESS | 1656 | Values: 3324.8 3335.0 3359.2 3376.8 3390.3 5517.9 5575.2 5585.4 5587.3 5700.3 5559.0 5676.9 5683.3 5711.0 5735.0 5132.4 5174.6 5218.7 5246.8 5296.9 5253.6 5262.5 5283.8 5316.1 5357.9 5106.6 5149.0 5251.7 5259.6 5274.6 5119.3 5154.7 5228.1 5238.6 5291.0 5141.3 5158.9 5179.9 5197.3 5267.3 5505.8 5543.6 5709.5 5726.7 5742.7 6065.8 6083.0 6111.1 6127.5 6190.2 6175.8 6175.8 6189.5 6214.4 6233.8 5778.5 5782.0 5800.2 5813.6 5855.4 6101.3 6122.8 6146.8 6148.5 6163.4 6049.7 6109.5 6112.2 6119.0 6139.8 5026.9 5403.0 5497.6 5639.4 6159.3 6040.6 6097.2 6101.0 6101.2 6109.6 5465.6 5708.7 6119.7 6123.0 6182.2 4324.2 4871.9 4906.1 4922.2 4986.7 3420.4 3685.0 4377.6 4418.8 4439.8 5285.2 5326.8 5576.1 5684.3 5777.2 6102.3 6107.2 6156.2 6160.8 6183.5 5536.9 5761.0 5800.9 5819.4 5820.7 5380.9 5396.3 5399.6 5409.7 5444.1 6128.2 6142.9 6172.3 6175.3 6190.7 6117.6 6119.7 6122.2 6123.5 6143.8 6295.1 6305.3 6310.3 6319.9 6349.7 5907.2 6046.9 6062.7 6091.4 6155.1 4798.9 6104.1 6132.7 6133.5 6165.7 6098.2 6114.2 6126.8 6133.4 6135.5 5128.3 5826.0 5947.1 6032.4 6120.4 5801.7 5905.5 5968.9 5974.6 6043.8 6020.7 6043.0 6049.3 6054.7 6083.0 5771.7 5844.9 5893.2 5906.4 5953.6 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | [#1] ext.html Cycles:180 Average:69109.06 Median:234608.79 stddev:149055.12 (63.5%) stddev-sans-first:149384.18 11:42:47 INFO - PROCESS | 1656 | Values: 475.8 478.2 507.0 523.9 563.9 31056.0 41563.8 41681.4 43838.3 43845.6 42819.6 43255.8 43385.8 43395.9 43442.1 9449.1 9656.7 9664.5 9667.2 9673.5 9529.7 9610.4 9617.3 9633.6 9642.7 9576.5 9585.0 9612.9 9626.6 9672.1 9556.9 9579.0 9618.3 9639.6 9651.5 9460.5 9544.6 9556.2 9569.6 9577.9 40282.0 40655.6 40680.6 40733.6 40824.8 3436.3 3591.7 3722.2 4087.0 4095.9 30627.4 30682.2 30740.2 30978.0 31023.5 3141.6 3171.5 3185.7 3228.7 3289.7 378069.1 426398.0 466635.0 468549.0 469217.0 51390.7 56483.0 58541.0 58989.0 59030.7 62214.7 62463.7 62581.7 62600.7 62790.0 13081.8 15856.6 15969.8 16037.0 16042.2 12393.3 12426.8 12509.9 12592.2 12701.5 13138.4 15535.0 16012.8 16024.6 16069.0 5966.3 7004.3 7153.3 7181.4 7201.3 881.2 893.0 980.1 1063.5 1076.9 28933.7 29453.4 29817.9 30148.8 30292.0 984.7 1076.2 1080.5 1099.4 1158.9 15226.0 15566.4 15581.1 15713.8 16005.2 626451.9 642842.0 649618.0 650233.0 651263.0 56833.4 57033.0 57481.4 57554.4 57921.1 1207.6 1375.3 1432.8 1438.4 1444.6 30129.5 30320.8 30938.4 31302.0 31677.0 49787.0 50522.7 50526.7 50598.5 50702.0 564141.0 568188.3 569376.0 569510.4 571012.0 42930.0 43650.6 43933.0 44052.9 44202.0 46515.0 47399.5 48054.3 48217.3 48721.0 38777.8 39026.0 39281.0 39902.2 40422.6 33400.0 38381.8 38509.8 39705.8 39774.6 37455.0 37771.9 38025.4 38579.0 38699.6 34076.0 34648.7 34850.0 35289.6 35358.0 21753.2 22975.5 23776.6 25642.9 26142.3 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | [#2] jquery.html Cycles:180 Average:32891.32 Median:25439.21 stddev:91633.29 (360.2%) stddev-sans-first:91857.73 11:42:47 INFO - PROCESS | 1656 | Values: 328.5 364.9 376.7 377.2 396.8 19319.9 20149.0 20304.6 20893.2 21035.0 19971.3 20225.1 20600.0 20758.6 20834.4 4778.6 5765.6 5848.1 5978.9 6049.3 5893.9 6005.7 6028.0 6028.4 6039.3 5678.0 5892.1 5938.3 5961.1 5995.4 5898.7 5920.2 5937.3 5975.3 5984.3 5005.8 5149.8 5183.8 5185.9 5188.7 25345.7 25391.1 25421.9 25456.6 25849.7 2231.5 2251.5 2426.1 2674.6 2969.8 14882.0 15453.5 15786.8 16074.9 16229.7 1851.3 1872.3 1888.9 1903.6 1914.6 114980.0 116754.4 117259.0 117464.0 117893.0 9102.9 10444.8 10588.0 10731.3 10970.7 13738.2 13765.2 13783.4 13791.9 13799.3 8275.3 8524.3 8556.1 8685.0 8689.4 8400.0 8494.2 8574.7 8687.4 8738.2 6583.9 6710.0 6711.5 6732.3 6761.9 1824.0 1844.5 1859.6 1864.1 1865.8 872.8 883.4 897.7 929.5 950.2 17365.0 21311.8 22190.3 22193.0 22230.2 938.9 965.2 991.6 1043.2 1052.8 11141.0 11688.9 11821.3 11842.5 11900.8 539450.0 542803.0 547876.0 561208.0 561322.0 104376.5 107074.0 107504.4 108716.0 108937.0 970.3 990.5 998.4 998.5 1003.5 18103.6 19120.7 19305.7 19306.2 19407.2 49845.8 50535.7 51160.7 51557.0 51719.7 41196.2 64728.0 73619.0 73915.6 74648.0 7739.3 7925.0 8043.8 8137.6 8189.2 12836.6 12854.0 13292.9 13339.7 13424.0 10667.6 11190.5 11416.8 11530.0 11586.5 10195.1 10451.1 11043.2 11500.9 11633.9 9216.8 10415.9 10931.8 11590.9 11818.0 7025.8 7319.4 7428.7 7650.9 7917.9 9721.0 11213.3 11593.7 11674.0 11744.2 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | [#3] mootools.html Cycles:180 Average:5607.89 Median:4828.17 stddev:3170.51 (65.7%) stddev-sans-first:3164.54 11:42:47 INFO - PROCESS | 1656 | Values: 1521.8 1548.2 1622.8 1782.3 1785.3 6525.3 7585.3 8107.4 8260.0 9142.1 8565.1 8848.1 8892.2 8941.4 8968.5 2073.0 2311.2 2388.1 2391.0 2393.1 2361.2 2374.5 2400.4 2403.5 2404.6 2350.4 2358.5 2371.3 2379.4 2389.9 2048.3 2339.7 2342.4 2358.8 2389.8 2373.0 2379.3 2380.5 2382.5 2384.6 8902.2 8963.6 8970.1 9003.6 9017.3 5183.6 5277.3 5397.6 5419.3 5445.0 3046.2 3603.4 3612.2 3686.0 3686.9 1022.8 1048.3 1058.2 1077.3 1080.3 11729.8 11746.5 11801.8 11821.2 11955.6 4618.8 4663.6 4853.5 4882.7 4887.9 9945.9 10039.2 10050.1 10051.7 10098.9 5396.1 5421.0 5422.6 5431.4 5440.4 4404.4 4559.0 4759.0 4919.3 4940.6 3912.4 3914.8 4009.6 4020.4 4054.8 1299.9 1316.2 1318.0 1325.1 1328.5 778.8 811.8 909.9 935.9 947.8 5120.2 5509.9 5608.8 5768.5 5863.4 417.7 432.5 435.4 436.6 441.5 3306.8 3776.6 3781.6 3835.7 3878.1 10603.6 10775.8 10895.6 10911.7 10938.8 7926.8 9203.0 10855.1 10866.9 10877.6 6589.8 8026.8 8326.7 8331.8 8359.5 4601.7 5528.1 5609.0 5611.2 5771.4 7893.6 8348.2 9363.7 10097.8 10113.7 8777.4 9574.4 10001.2 10033.9 10228.5 6905.3 7112.9 7468.5 7651.8 7961.2 8157.3 8388.0 8413.4 8481.3 8500.5 7395.1 7465.4 7475.6 7499.9 7530.9 7267.8 7325.3 7346.3 7355.3 7358.7 6740.9 6742.6 6829.8 6842.2 6842.2 4420.7 4802.9 4929.3 4961.7 5011.6 4800.9 4867.5 4923.9 4963.1 4972.5 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | [#4] prototype.html Cycles:180 Average:4352.54 Median:4681.31 stddev:1316.25 (28.1%) stddev-sans-first:1284.31 11:42:47 INFO - PROCESS | 1656 | Values: 299.5 302.6 309.3 311.9 313.9 5181.4 5416.3 5518.8 5564.6 5601.7 4551.7 5340.5 5363.6 5444.1 5460.0 3823.6 4105.1 4181.6 4195.9 4237.7 3971.5 4181.0 4224.5 4232.8 4253.4 3343.9 3794.8 4144.4 4151.2 4171.2 3856.0 4170.5 4188.7 4220.6 4230.1 3486.5 3602.6 3655.4 3658.4 3659.5 4020.8 4597.4 5149.9 5231.9 5320.9 4117.9 4659.8 4819.2 4856.7 4858.1 3995.0 5564.3 5660.5 5666.5 5858.5 3708.9 3960.0 4003.2 4005.7 4013.3 5238.6 5242.5 5636.0 6061.6 6140.3 3639.0 3762.6 3766.6 4057.3 4095.9 3691.1 4141.4 4149.9 4153.0 4162.5 3984.5 4779.4 4797.5 4832.8 4833.5 4702.8 4776.6 5570.6 5736.4 5809.4 1171.6 1229.1 1371.3 1410.7 1422.2 759.9 813.4 848.9 880.4 881.4 3946.2 4140.4 4417.7 4468.7 4587.0 4188.3 4970.6 4976.5 4980.4 5016.5 4194.0 4339.5 4442.1 4523.9 4591.6 2094.9 2389.6 2470.7 2507.7 2605.9 4360.5 4522.8 4571.9 4863.5 5236.5 4543.1 5045.8 5070.9 5162.6 5206.0 3871.9 4442.5 4578.0 5144.9 5182.1 4587.8 4767.3 4894.8 4979.8 5006.5 4260.0 4904.6 4948.3 4964.1 4998.1 4459.1 4720.8 4928.6 5155.5 5255.3 3229.4 3462.7 3912.6 3969.2 4059.8 5325.9 5369.7 5660.8 5888.7 5905.8 4516.0 5322.8 5738.9 5771.8 5842.8 4577.0 5122.7 5601.8 5673.0 5729.8 5118.4 5160.9 5232.2 5669.2 5721.4 3852.1 4785.3 5469.0 5771.2 5771.8 5569.0 5635.9 5724.9 5733.3 5735.2 11:42:47 INFO - PROCESS | 1656 | 11:42:47 INFO - PROCESS | 1656 | [#5] yui.html Cycles:180 Average:22483.41 Median:4374.74 stddev:35377.52 (808.7%) stddev-sans-first:35439.00 11:42:47 INFO - PROCESS | 1656 | Values: 713.3 730.2 762.8 776.9 790.1 12890.9 13097.4 14984.9 16430.3 16559.7 12267.6 14835.4 14912.0 14963.7 15008.8 861.4 878.6 880.4 880.8 886.4 881.0 883.4 885.5 887.2 887.8 904.8 921.1 921.5 922.0 925.2 882.3 908.2 911.5 916.1 922.3 655.5 680.2 683.3 683.3 686.1 12571.2 13212.3 13999.7 14012.6 14041.8 11535.8 11916.2 12036.9 12358.5 12375.4 10467.7 10910.2 10923.6 11081.5 11106.3 3663.5 3696.4 3716.8 3847.0 3862.7 111513.0 111788.0 112151.0 113923.4 115744.4 9186.2 9247.7 9268.7 9417.3 9624.0 19626.8 19803.8 20049.9 20054.2 20057.4 7751.3 7764.0 7815.7 7817.7 8037.5 4016.5 4250.9 4298.0 4309.4 4440.0 3523.6 3628.0 3637.3 3711.8 3886.9 613.9 620.9 653.8 695.3 700.1 580.9 680.5 697.3 702.2 703.0 9241.7 10092.6 10138.9 10216.4 10365.8 620.8 651.5 653.2 690.5 695.9 4177.0 4498.7 4654.3 4705.1 4738.6 86722.0 88113.6 116676.0 140270.0 143614.0 82337.0 84829.0 91917.2 123949.0 124117.0 105590.5 106638.0 107042.9 108465.8 108531.0 7580.3 8925.3 9136.9 9145.7 9159.0 42740.6 48468.3 54630.0 60930.0 62854.7 88929.0 92534.0 94221.5 94361.3 95142.0 11441.9 11659.2 11923.6 12004.8 12208.3 11248.9 13077.0 13622.9 13697.7 13701.5 11876.0 12120.5 12208.9 12363.1 12508.3 11916.8 12111.0 12483.8 12503.2 12589.9 11785.8 11882.1 11946.5 12360.9 12368.9 9481.8 9645.9 9761.5 9777.5 9978.6 8784.4 9175.3 9562.0 9562.3 10571.8 11:42:47 INFO - PROCESS | 1656 | -------- Summary: end -------- 11:42:47 INFO - PROCESS | 1656 | 11:42:48 INFO - TEST-INFO | 1656: exit 0 11:42:48 INFO - TEST-OK | dromaeo_css | took 1115891ms 11:42:48 INFO - TEST-START | kraken 11:42:48 INFO - operating with platform_type : mac_ 11:42:48 INFO - Initialising browser for kraken test... 11:42:48 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmp4enDOq/profile http://localhost:49280/getInfo.html) 11:42:50 INFO - PROCESS | 1664 | 2016-10-19 11:42:50.873 plugin-container[1665:10327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 11:42:50 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:50 INFO - PROCESS | 1664 | 2016-10-19 11:42:50.876 plugin-container[1665:10327] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 11:42:50 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:50 INFO - PROCESS | 1664 | 2016-10-19 11:42:50.877 plugin-container[1665:10327] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:42:50 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 11:42:50 INFO - PROCESS | 1664 | colorDepth:24 11:42:50 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 11:42:50 INFO - PROCESS | 1664 | __metrics 11:42:51 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 11:42:51 INFO - TEST-INFO | 1664: exit 0 11:42:51 INFO - Browser initialized. 11:42:51 INFO - Running cycle 1/1 for kraken test... 11:42:51 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/T/tmp4enDOq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:54 INFO - PROCESS | 1666 | 2016-10-19 11:42:54.026 plugin-container[1667:10441] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x884f, name = 'com.apple.tsm.portname' 11:42:54 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:54 INFO - PROCESS | 1666 | 2016-10-19 11:42:54.026 plugin-container[1667:10441] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4313, name = 'com.apple.CFPasteboardClient' 11:42:54 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 11:42:54 INFO - PROCESS | 1666 | 2016-10-19 11:42:54.026 plugin-container[1667:10441] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 11:43:03 INFO - PROCESS | 1666 | RSS: Main: 237264896 11:43:03 INFO - PROCESS | 1666 | 11:43:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection) 11:43:07 INFO - PROCESS | 1666 | RSS: Main: 249704448 11:43:07 INFO - PROCESS | 1666 | 11:43:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-dft) 11:43:10 INFO - PROCESS | 1666 | RSS: Main: 258342912 11:43:10 INFO - PROCESS | 1666 | 11:43:14 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-fft) 11:43:14 INFO - PROCESS | 1666 | RSS: Main: 260296704 11:43:14 INFO - PROCESS | 1666 | 11:43:16 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator) 11:43:17 INFO - PROCESS | 1666 | RSS: Main: 261214208 11:43:17 INFO - PROCESS | 1666 | 11:43:19 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:43:20 INFO - PROCESS | 1666 | RSS: Main: 262791168 11:43:20 INFO - PROCESS | 1666 | 11:43:22 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom) 11:43:23 INFO - PROCESS | 1666 | RSS: Main: 263258112 11:43:23 INFO - PROCESS | 1666 | 11:43:26 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate) 11:43:27 INFO - PROCESS | 1666 | RSS: Main: 263348224 11:43:27 INFO - PROCESS | 1666 | 11:43:30 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial) 11:43:30 INFO - PROCESS | 1666 | RSS: Main: 263610368 11:43:30 INFO - PROCESS | 1666 | 11:43:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:43:32 INFO - PROCESS | 1666 | RSS: Main: 263303168 11:43:32 INFO - PROCESS | 1666 | 11:43:34 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:43:35 INFO - PROCESS | 1666 | RSS: Main: 263413760 11:43:35 INFO - PROCESS | 1666 | 11:43:37 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:43:38 INFO - PROCESS | 1666 | RSS: Main: 263426048 11:43:38 INFO - PROCESS | 1666 | 11:43:41 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:43:42 INFO - PROCESS | 1666 | RSS: Main: 263753728 11:43:42 INFO - PROCESS | 1666 | 11:43:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:43:45 INFO - PROCESS | 1666 | RSS: Main: 263696384 11:43:45 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49280/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49280/tests/kraken/driver.html?testName=ai-astar) 11:43:47 INFO - PROCESS | 1666 | RSS: Main: 265371648 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | __start_tp_report 11:43:47 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 11:43:47 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 11:43:47 INFO - PROCESS | 1666 | |i|pagename|runs| 11:43:47 INFO - PROCESS | 1666 | |0;ai-astar;105;117;116;118;116;117;117;117;124;157 11:43:47 INFO - PROCESS | 1666 | |1;audio-beat-detection;149;122;115;151;115;118;115;116;145;117 11:43:47 INFO - PROCESS | 1666 | |2;audio-dft;192;193;209;227;261;275;195;196;191;194 11:43:47 INFO - PROCESS | 1666 | |3;audio-fft;79;58;60;85;68;84;71;58;79;59 11:43:47 INFO - PROCESS | 1666 | |4;audio-oscillator;82;70;86;72;69;70;89;72;100;115 11:43:47 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;88;85;88;85;87;85;89;89;88;85 11:43:47 INFO - PROCESS | 1666 | |6;imaging-darkroom;98;106;137;149;99;113;125;99;98;101 11:43:47 INFO - PROCESS | 1666 | |7;imaging-desaturate;90;88;107;128;82;133;76;138;82;134 11:43:47 INFO - PROCESS | 1666 | |8;json-parse-financial;57;57;55;57;57;58;58;58;59;58 11:43:47 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;71;71;80;77;79;69;69;68;71;71 11:43:47 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;88;111;108;81;88;107;109;87;90;87 11:43:47 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;149;161;160;162;150;159;145;163;266;142 11:43:47 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;176;178;171;235;213;234;188;171;174;175 11:43:47 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;69;71;68;70;72;69;68;70;70;68 11:43:47 INFO - PROCESS | 1666 | __end_tp_report 11:43:47 INFO - PROCESS | 1666 | __start_cc_report 11:43:47 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,948 11:43:47 INFO - PROCESS | 1666 | __end_cc_report 11:43:47 INFO - PROCESS | 1666 | __startTimestamp1476902627912__endTimestamp 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | ------- Summary: start ------- 11:43:47 INFO - PROCESS | 1666 | Number of tests: 14 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:120.40 Median:117.00 stddev:13.66 (11.7%) stddev-sans-first:13.31 11:43:47 INFO - PROCESS | 1666 | Values: 105.0 117.0 116.0 118.0 116.0 117.0 117.0 117.0 124.0 157.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:126.30 Median:120.00 stddev:15.41 (12.8%) stddev-sans-first:13.99 11:43:47 INFO - PROCESS | 1666 | Values: 149.0 122.0 115.0 151.0 115.0 118.0 115.0 116.0 145.0 117.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:213.30 Median:202.50 stddev:31.02 (15.3%) stddev-sans-first:31.93 11:43:47 INFO - PROCESS | 1666 | Values: 192.0 193.0 209.0 227.0 261.0 275.0 195.0 196.0 191.0 194.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:70.10 Median:75.00 stddev:11.04 (14.7%) stddev-sans-first:11.23 11:43:47 INFO - PROCESS | 1666 | Values: 79.0 58.0 60.0 85.0 68.0 84.0 71.0 58.0 79.0 59.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:82.50 Median:72.00 stddev:15.39 (21.4%) stddev-sans-first:16.33 11:43:47 INFO - PROCESS | 1666 | Values: 82.0 70.0 86.0 72.0 69.0 70.0 89.0 72.0 100.0 115.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.73 (2.0%) stddev-sans-first:1.79 11:43:47 INFO - PROCESS | 1666 | Values: 88.0 85.0 88.0 85.0 87.0 85.0 89.0 89.0 88.0 85.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:112.50 Median:123.50 stddev:18.40 (14.9%) stddev-sans-first:18.76 11:43:47 INFO - PROCESS | 1666 | Values: 98.0 106.0 137.0 149.0 99.0 113.0 125.0 99.0 98.0 101.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:105.80 Median:79.00 stddev:25.06 (31.7%) stddev-sans-first:25.92 11:43:47 INFO - PROCESS | 1666 | Values: 90.0 88.0 107.0 128.0 82.0 133.0 76.0 138.0 82.0 134.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:58.00 stddev:1.07 (1.9%) stddev-sans-first:1.13 11:43:47 INFO - PROCESS | 1666 | Values: 57.0 57.0 55.0 57.0 57.0 58.0 58.0 58.0 59.0 58.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:72.60 Median:71.00 stddev:4.38 (6.2%) stddev-sans-first:4.60 11:43:47 INFO - PROCESS | 1666 | Values: 71.0 71.0 80.0 77.0 79.0 69.0 69.0 68.0 71.0 71.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:95.60 Median:87.00 stddev:11.59 (13.3%) stddev-sans-first:11.96 11:43:47 INFO - PROCESS | 1666 | Values: 88.0 111.0 108.0 81.0 88.0 107.0 109.0 87.0 90.0 87.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:165.70 Median:160.50 stddev:36.05 (22.5%) stddev-sans-first:37.73 11:43:47 INFO - PROCESS | 1666 | Values: 149.0 161.0 160.0 162.0 150.0 159.0 145.0 163.0 266.0 142.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.50 Median:183.00 stddev:25.85 (14.1%) stddev-sans-first:26.80 11:43:47 INFO - PROCESS | 1666 | Values: 176.0 178.0 171.0 235.0 213.0 234.0 188.0 171.0 174.0 175.0 11:43:47 INFO - PROCESS | 1666 | 11:43:47 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.50 Median:70.00 stddev:1.35 (1.9%) stddev-sans-first:1.42 11:43:47 INFO - PROCESS | 1666 | Values: 69.0 71.0 68.0 70.0 72.0 69.0 68.0 70.0 70.0 68.0 11:43:47 INFO - PROCESS | 1666 | -------- Summary: end -------- 11:43:47 INFO - PROCESS | 1666 | 11:43:48 INFO - TEST-INFO | 1666: exit 0 11:43:48 INFO - TEST-OK | kraken | took 60444ms 11:43:48 INFO - SUITE-END | took 1176s 11:43:49 INFO - Completed test suite (00:19:36) 11:43:49 INFO - javascript benchmark 11:43:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7215.542518551206, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3324.817135057572, 3335.0, 3359.2273777031273, 3376.7805092668973, 3390.254144088303, 5517.862053448665, 5575.163725441186, 5585.385607583163, 5587.3015873015875, 5700.287464066992, 5559.0, 5676.914846277309, 5683.289588801389, 5711.0, 5735.0250457422235, 5132.384113906336, 5174.586033117342, 5218.712970786605, 5246.816361427332, 5296.867578310535, 5253.6322457428, 5262.473752624745, 5283.8150664726745, 5316.0962636351915, 5357.866053348658, 5106.5659418949435, 5148.974255128738, 5251.711155886463, 5259.552938000236, 5274.630775845692, 5119.334486516766, 5154.690718556861, 5228.058949389147, 5238.607104467142, 5290.973545132289, 5141.254518094893, 5158.9484105158845, 5179.922301165486, 5197.272381866542, 5267.28891599633, 5505.779768809283, 5543.639663421905, 5709.514691251248, 5726.656400615976, 5742.7415766290615, 6065.787697430569, 6083.026715725463, 6111.105587097678, 6127.494965205055, 6190.164327815777, 6175.7529698811995, 6175.84560385994, 6189.535784816157, 6214.378562143795, 6233.78959835685, 5778.479936805624, 5781.988152073371, 5800.197745306026, 5813.563982701315, 5855.355910849803, 6101.277634935909, 6122.816315510542, 6146.78486252979, 6148.538859585459, 6163.3836616338, 6049.697515124226, 6109.541784366191, 6112.205413296243, 6118.969405152946, 6139.84650383744, 5026.919212369358, 5402.972985135128, 5497.615166938356, 5639.407862174413, 6159.3224745277985, 6040.607360521552, 6097.161734506633, 6101.035369327992, 6101.206843110367, 6109.556495504338, 5465.551628818366, 5708.65748055118, 6119.654885716942, 6123.038605230386, 6182.165407669907, 4324.221640104748, 4871.903821640102, 4906.1414252505665, 4922.212446008622, 4986.725730084808, 3420.350133474632, 3684.9631503685155, 4377.627901628397, 4418.779061046935, 4439.755813430293, 5285.154375299933, 5326.8014696692935, 5576.079946808731, 5684.289463817023, 5777.248957635565, 6102.277183976845, 6107.249501910372, 6156.199694039836, 6160.845978850564, 6183.474404675653, 5536.944630553723, 5761.0, 5800.883982320414, 5819.388964158787, 5820.650760954356, 5380.91928621071, 5396.325459317585, 5399.595030372738, 5409.675419474844, 5444.128939369682, 6128.197586012462, 6142.877142457126, 6172.321044685171, 6175.289841668266, 6190.721417536131, 6117.583823141572, 6119.715820550657, 6122.215193971364, 6123.4961516426065, 6143.757528502154, 6295.118683384421, 6305.306416294296, 6310.305866354605, 6319.936800631935, 6349.6507692077375, 5907.172995780508, 6046.941785187662, 6062.7271772769445, 6091.360407157184, 6155.138280640804, 4798.856034318976, 6104.125697713593, 6132.661461321697, 6133.457080270946, 6165.691715414301, 6098.207233059674, 6114.174586430775, 6126.754929802758, 6133.417325354084, 6135.509159267159, 5128.282040514406, 5826.0, 5947.137665038632, 6032.426919442646, 6120.387961203844, 5801.680907550125, 5905.4685078343155, 5968.850778730566, 5974.581779275409, 6043.848903777441, 6020.729067192075, 6042.969785151046, 6049.274087109575, 6054.7275372607455, 6083.0, 5771.653700777967, 5844.883102337844, 5893.204417403767, 5906.438888305604, 5953.613015154001], "value": 5605.004794655492, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [475.8191887082908, 478.23721164742267, 506.9543741063305, 523.8559396166054, 563.8956792993298, 31056.0, 41563.79218103909, 41681.3747793783, 43838.34242486365, 43845.561544384545, 42819.57180428195, 43255.78372108135, 43385.78307108468, 43395.91510212238, 43442.13115737683, 9449.102335278154, 9656.710298691016, 9664.468454235015, 9667.226621870268, 9673.516324183798, 9529.666461673844, 9610.423374597547, 9617.278704097185, 9633.61465541381, 9642.662506812263, 9576.47329396883, 9585.0, 9612.903870961305, 9626.566804493814, 9672.081152290557, 9556.90443095567, 9578.952105239465, 9618.326717129803, 9639.566219520138, 9651.517424128766, 9460.479673617949, 9544.570494327772, 9556.187724043395, 9569.569369378325, 9577.904220957771, 40282.0, 40655.593444065475, 40680.59319406797, 40733.59266407328, 40824.79587602043, 3436.329915666446, 3591.748577599569, 3722.236941427001, 4087.0171246515274, 4095.9385609215587, 30627.387452251052, 30682.23294417635, 30740.2314942126, 30978.0, 31023.53464698031, 3141.575887255214, 3171.4767063434506, 3185.7132858042887, 3228.725558327545, 3289.73682105431, 378069.10965445, 426398.0, 466635.0, 468549.0, 469217.0, 51390.715232119495, 56483.0, 58541.0, 58989.0, 59030.70484647549, 62214.68892655508, 62463.68768156221, 62581.68709156425, 62600.68699656473, 62790.03494667999, 13081.803772943413, 15856.60358491047, 15969.840301597067, 16037.0, 16042.197890105448, 12393.256404615751, 12426.813597796041, 12509.874901251053, 12592.181508201938, 12701.491940322283, 13138.4087716053, 15535.0, 16012.759808602881, 16024.599385015235, 16069.0, 5966.254218222723, 7004.2645522220455, 7153.323101528359, 7181.35367816899, 7201.3158749918675, 881.2421317666801, 892.973210803664, 980.122790102854, 1063.5426766490484, 1076.8653918260218, 28933.71066289352, 29453.410931781244, 29817.850910745743, 30148.84925575358, 30292.0, 984.699666601679, 1076.2143635146388, 1080.5191689698008, 1099.433791597328, 1158.901493373056, 15226.0, 15566.377344906077, 15581.065136091871, 15713.764293535605, 16005.199740012953, 626451.8677406583, 642842.0, 649618.0, 650233.0, 651263.0, 56833.431665682816, 57033.0, 57481.42518574844, 57554.42445575491, 57921.131183031444, 1207.6014915078003, 1375.3467103125936, 1432.7850822376727, 1438.4318194313187, 1444.5882923367, 30129.548056779604, 30320.84839575788, 30938.381232375676, 31302.0, 31677.0, 49787.0, 50522.74738626283, 50526.747366262935, 50598.45810793356, 50701.98596028132, 564141.0, 568188.3181168135, 569376.0, 569510.3720332512, 571012.0, 42930.0, 43650.56349436465, 43933.0, 44052.89867753332, 44202.0, 46515.0, 47399.52600473951, 48054.27918581154, 48217.27674084821, 48721.0, 38777.806110969264, 39026.0, 39281.0, 39902.201955961296, 40422.59577404188, 33400.0, 38381.80809095937, 38509.807450962566, 39705.80147099246, 39774.60225397825, 37455.0, 37771.92254426033, 38025.4296185563, 38579.0, 38699.6130038696, 34075.97772066842, 34648.65351346555, 34849.954501365, 35289.64710352966, 35358.0, 21753.238636647642, 22975.540489190458, 23776.57340559572, 25642.8717856417, 26142.34644133912], "value": 20076.546444770138, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.51707989255794, 364.85405837664933, 376.7362846007795, 377.15517241379285, 396.8055652730163, 19319.903400482995, 20149.0, 20304.593908121828, 20893.164273429084, 21035.0, 19971.301004464847, 20225.089870955806, 20600.0, 20758.584828303425, 20834.374968750963, 4778.641601879855, 5765.596408251414, 5848.118956056034, 5978.850528736794, 6049.274087109553, 5893.8821223575505, 6005.729742161613, 6027.969860150693, 6028.367021462749, 6039.275286965571, 5678.0, 5892.116182572627, 5938.346781853993, 5961.105834124873, 5995.411216027735, 5898.734556944926, 5920.168921095779, 5937.317208521011, 5975.282966044059, 5984.281886173643, 5005.799768009275, 5149.845504634867, 5183.844484665466, 5185.922211166836, 5188.7146206958605, 25345.74654253452, 25391.111311104207, 25421.8560164793, 25456.563363945814, 25849.707514624195, 2231.475603233239, 2251.549690061994, 2426.116667082318, 2674.598810178469, 2969.752703864382, 14882.0, 15453.459128930546, 15786.763198552031, 16074.919625401799, 16229.675406491804, 1851.3427733154806, 1872.26981477224, 1888.8961107139103, 1903.6097599991967, 1914.5787926656114, 114980.0, 116754.41622791917, 117259.0, 117464.0, 117892.99999999914, 9102.90897091034, 10444.791104178026, 10588.047075763217, 10731.302465339728, 10970.670879873456, 13738.175709457464, 13765.174089554459, 13783.379747911367, 13791.862081379259, 13799.310034498234, 8275.337972962148, 8524.27543658796, 8556.101609331055, 8685.0, 8689.43661619811, 8400.0, 8494.193051660082, 8574.657013719507, 8687.39188256816, 8738.169873861973, 6583.9012414813815, 6710.0, 6711.463082953352, 6732.2931092235585, 6761.898571521431, 1823.963520729578, 1844.4558855137766, 1859.5722983713822, 1864.142494452567, 1865.8414034807192, 872.82543491302, 883.412530667112, 897.7486303834806, 929.5259417697023, 950.2208189284859, 17364.95810251389, 21311.78688213098, 22190.334289971328, 22193.0, 22230.221942231943, 938.9201917836924, 965.1603105298295, 991.5785791038924, 1043.2175868098925, 1052.8262836632023, 11141.0, 11688.883111168949, 11821.290722556674, 11842.467088981017, 11900.821487677691, 539450.0, 542803.0, 547876.0, 561208.0, 561322.0, 104376.47811760893, 107074.0, 107504.38743418855, 108716.0, 108937.0, 970.262600423669, 990.4552496127101, 998.4209158687944, 998.4508520313943, 1003.5433877585681, 18103.637927241645, 19120.713189301892, 19305.710414343514, 19306.227750889808, 19407.2237110514, 49845.75077124591, 50535.74732126316, 51160.74419627878, 51557.0, 51719.707007325116, 41196.17607647771, 64728.0, 73619.0, 73915.63042184754, 74648.0, 7739.3421559165845, 7924.960375198087, 8043.839123217386, 8137.59312034384, 8189.22202390772, 12836.614901552968, 12854.012011322484, 13292.867071329163, 13339.733205336033, 13423.993200509805, 10667.572278295698, 11190.496427660937, 11416.771664566828, 11530.019948304489, 11586.536538538365, 10195.082442580204, 10451.059404653617, 11043.226974111734, 11500.884991149982, 11633.941830290794, 9216.769580760534, 10415.895841041493, 10931.781364372828, 11590.94204528972, 11817.995470384769, 7025.824354391181, 7319.377852882564, 7428.73999410018, 7650.885236721342, 7917.920820791718, 9721.0, 11213.271137376045, 11593.6521904343, 11674.007709344802, 11744.236624619714], "value": 9639.33439027799, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1521.8021657184568, 1548.241361732751, 1622.756586512023, 1782.3494424535033, 1785.3126546279686, 6525.347465253484, 7585.317321441069, 8107.351411887043, 8259.95870020649, 9142.131497507735, 8565.143485651432, 8848.115188481148, 8892.244159246457, 8941.374103812735, 8968.461892286467, 2073.046398656616, 2311.1564279038116, 2388.080588973249, 2390.988045059772, 2393.102586530051, 2361.2207971369407, 2374.489484760776, 2400.3879010852206, 2403.483251101013, 2404.591219492682, 2350.4476448034698, 2358.481134150484, 2371.300466362419, 2379.4170428245106, 2389.892454839536, 2048.2933387981125, 2339.6724458575814, 2342.4143964009, 2358.8348815582917, 2389.7968672662846, 2372.9525409491885, 2379.3337865397716, 2380.4762952150672, 2382.4996750682376, 2384.6423036544484, 8902.243309318716, 8963.55182240892, 8970.102989701043, 9003.639854405881, 9017.323700722472, 5183.585313174937, 5277.261183434322, 5397.649152805055, 5419.34967803866, 5445.0, 3046.2232130806724, 3603.387424137903, 3612.187257866985, 3686.0416291764336, 3686.889393318205, 1022.7698767777265, 1048.2557384257186, 1058.2327812336073, 1077.2782235901964, 1080.3193987787722, 11729.765404691858, 11746.530138794524, 11801.763964720743, 11821.231619944676, 11955.641330760003, 4618.769061546976, 4663.603593694506, 4853.490383509751, 4882.681675947545, 4887.926681099786, 9945.850812237823, 10039.247056470795, 10050.145737612238, 10051.69844904654, 10098.949505252427, 5396.055690254189, 5421.0, 5422.593305502024, 5431.429699881534, 5440.4287549807495, 4404.383386325917, 4558.997020655451, 4758.95241047592, 4919.2867034279825, 4940.629452791055, 3912.374020156761, 3914.784686842251, 4009.6190861868076, 4020.3768415895584, 4054.8175332110127, 1299.9405484530078, 1316.2365827819842, 1318.0444177970894, 1325.0592079623575, 1328.5283724277936, 778.8442311537715, 811.7564730580802, 909.8953620333747, 935.8596210568469, 947.8056998315329, 5120.206368012964, 5509.944900551023, 5608.775648974077, 5768.538516918637, 5863.355030946593, 417.70551761008414, 432.5263836099466, 435.4187160141184, 436.6288654643591, 441.46803102261697, 3306.834658267077, 3776.5656949451163, 3781.5840257571645, 3835.6547910688173, 3878.127421330178, 10603.628872989408, 10775.78448431027, 10895.618653347095, 10911.672649820519, 10938.835917461245, 7926.841463170819, 9202.953985230031, 10855.131589472823, 10866.891331086747, 10877.564897404016, 6589.80230593083, 8026.7591972240925, 8326.6669333226, 8331.750047498585, 8359.456635318686, 4601.723896566216, 5528.115501519737, 5609.046462101534, 5611.158326251095, 5771.422857714195, 7893.644785984759, 8348.165183481604, 9363.719088427359, 10097.798044039226, 10113.747156321151, 8777.385583009129, 9574.377665452002, 10001.249906257206, 10033.899661003297, 10228.53971571296, 6905.343992320722, 7112.933060041035, 7468.477206595703, 7651.770446886602, 7961.243681850215, 8157.265846073882, 8388.035375931846, 8413.368997325348, 8481.321494280566, 8500.489970601784, 7395.075615548057, 7465.365443937326, 7475.626218689153, 7499.887501687588, 7530.924690753022, 7267.7819665410125, 7325.304096110861, 7346.33882950537, 7355.301246381585, 7358.742444014434, 6740.898886516804, 6742.595444273359, 6829.795106146824, 6842.213145488134, 6842.213145488333, 4420.7126536775, 4802.855914322576, 4929.285253638274, 4961.7271050092595, 5011.649184557192, 4800.879978000578, 4867.537583929521, 4923.876903077308, 4963.081829861487, 4972.5027497249985], "value": 4439.288395091944, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.47890670233784, 302.6459042919784, 309.3441903165289, 311.94073126106025, 313.85562641185055, 5181.41444821333, 5416.322959630046, 5518.751656175472, 5564.610477266593, 5601.711606330541, 4551.749653769041, 5340.465953404658, 5363.570914326854, 5444.128939369702, 5459.972700136494, 3823.604384399688, 4105.126411511938, 4181.644560212386, 4195.85314513992, 4237.724547904377, 3971.5035620547433, 4181.014018341294, 4224.493060832704, 4232.788360581974, 4253.361995700654, 3343.882964096257, 3794.753341032838, 4144.41978123063, 4151.239425781851, 4171.228322760299, 3856.0, 4170.52039015514, 4188.6649068074485, 4220.556841531657, 4230.111676547944, 3486.546748922624, 3602.5676918769655, 3655.4334078217917, 3658.432942893856, 3659.524261845943, 4020.7587544747407, 4597.402337696078, 5149.92309075291, 5231.895362092775, 5320.920186197211, 4117.91764164715, 4659.79030943605, 4819.156647586658, 4856.684315519516, 4858.125537403266, 3994.960050399517, 5564.33228012637, 5660.5471562274915, 5666.46168613981, 5858.53131749459, 3708.9443658345144, 3959.980200098981, 4003.199360127957, 4005.679545636342, 4013.2776100301935, 5238.554722848562, 5242.528172464458, 5635.971820140873, 6061.605995610315, 6140.324564297924, 3638.9803658969, 3762.5861155272896, 3766.642168993941, 4057.3102572562734, 4095.8976025599004, 3691.1141326082084, 4141.420201171838, 4149.937750933738, 4153.003279213028, 4162.4796900387455, 3984.5019372578427, 4779.354787103696, 4797.520247975244, 4832.830850920201, 4833.468318484964, 4702.835400760957, 4776.593989510861, 5570.597698304665, 5736.42635736423, 5809.390014048548, 1171.5957994491887, 1229.1457437081322, 1371.300636675283, 1410.6967002094393, 1422.160925054223, 759.9278068583476, 813.3818298093491, 848.8540470364958, 880.3969281042379, 881.3962435731545, 3946.210757848442, 4140.385249752599, 4417.712848664891, 4468.73187608738, 4587.0, 4188.267053265667, 4970.577500912391, 4976.527229913152, 4980.427250866197, 5016.498350164954, 4194.0, 4339.5443478434945, 4442.111577684476, 4523.909521809545, 4591.586757191802, 2094.863833850795, 2389.593769059264, 2470.7035155781423, 2507.6740023797156, 2605.8566778826967, 4360.520342762294, 4522.751248681354, 4571.885702857455, 4863.5166274286685, 5236.486486486431, 4543.114092751882, 5045.84862454127, 5070.949290507047, 5162.638615296968, 5206.01085793698, 3871.8838434847, 4442.48911375196, 4578.03861189154, 5144.890594856091, 5182.149817600305, 4587.788548016469, 4767.261074533453, 4894.779734912009, 4979.825706100269, 5006.524380183877, 4259.97870010648, 4904.583110435653, 4948.2824990375475, 4964.10646083694, 4998.077278425078, 4459.101583970445, 4720.834770783006, 4928.605711542997, 5155.53600175986, 5255.265762298356, 3229.35193141631, 3462.705670017976, 3912.5891781362548, 3969.2061587683156, 4059.7564146151326, 5325.893482130413, 5369.677819330853, 5660.782931669711, 5888.735006924613, 5905.822825315247, 4515.9774201128785, 5322.828977624917, 5738.885222295614, 5771.769129234952, 5842.795502157404, 4576.954230457653, 5122.718250496258, 5601.775928962796, 5672.9505041567445, 5729.828105156852, 5118.436971933159, 5160.870978225575, 5232.1628539433505, 5669.229962075174, 5721.399253078367, 3852.114013776791, 4785.306130611111, 5469.0155771959835, 5771.2208851806145, 5771.8442721240635, 5569.0, 5635.942880848543, 5724.942750572441, 5733.254676891977, 5735.197072409783], "value": 3939.3272408779726, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.3259070178681, 730.1566690472506, 762.7711686494052, 776.9106552746435, 790.1111249843925, 12890.935545322272, 13097.410616522255, 14984.850151498482, 16430.260638271277, 16559.66880662386, 12267.63197104086, 14835.406583736638, 14912.0, 14963.700725985474, 15008.774868376955, 861.4228466927158, 878.5914549734372, 880.3969281042474, 880.7886107334226, 886.4238245140646, 881.030866047346, 883.3904605821995, 885.5395194498857, 887.1704955866253, 887.8002449448854, 904.7647611620993, 921.0789210789211, 921.5162039929024, 921.9631214751402, 925.1765928323797, 882.298572634757, 908.1826356279402, 911.4804561400005, 916.1434059154678, 922.2990527199377, 655.5149189599709, 680.2245440198178, 683.3269229808634, 683.3439897698215, 686.1423220973783, 12571.18287311322, 13212.33938303081, 13999.720005599831, 14012.579622611338, 14041.78937315941, 11535.76928461426, 11916.225445346025, 12036.879631203663, 12358.505659773598, 12375.381230938507, 10467.738306542322, 10910.181736369805, 10923.563057477691, 11081.501332440059, 11106.278091924, 3663.523741913534, 3696.3900956342172, 3716.8141592920247, 3846.961530384688, 3862.6909847212155, 111513.0, 111788.0, 112151.0, 113923.43038284838, 115744.42127789391, 9186.219171370509, 9247.72256832296, 9268.675596354231, 9417.293702972305, 9624.03759624032, 19626.803731962784, 19803.801961980196, 20049.899750501154, 20054.197832086844, 20057.39827805168, 7751.263629955145, 7763.990681211407, 7815.726449574237, 7817.726379576688, 8037.477563958324, 4016.5380981187536, 4250.893727656771, 4298.0, 4309.439772829574, 4440.045390241114, 3523.5771707395274, 3628.0385697790107, 3637.3270944875285, 3711.7958512281543, 3886.9222615547533, 613.871087071711, 620.8634100497883, 653.8201994451564, 695.3255342317943, 700.0758998122532, 580.9477147056785, 680.4590350671228, 697.3375293471223, 702.2415790945767, 703.0, 9241.676541321021, 10092.596296148218, 10138.949305253427, 10216.387016779017, 10365.792684146425, 620.7655450449787, 651.4560342114388, 653.2128784814288, 690.4648897104784, 695.9247961898777, 4177.0, 4498.707584007029, 4654.25531914892, 4705.106029854317, 4738.644601654821, 86722.0, 88113.55943220243, 116676.0, 140270.0, 143614.0, 82337.0, 84829.0, 91917.16165676649, 123949.0, 124117.0, 105590.47204764081, 106638.0, 107042.92957070486, 108465.83068338747, 108530.99999999841, 7580.279873412017, 8925.285977121945, 9136.853437534244, 9145.679901203426, 9159.0, 42740.572594274905, 48468.3036093735, 54630.0, 60930.0, 62854.68572657107, 88929.0, 92534.0, 94221.5288923551, 94361.2612297331, 95142.0, 11441.913018263253, 11659.242149260272, 11923.582674606347, 12004.819927701266, 12208.32854193028, 11248.94375528117, 13077.0, 13622.93188534051, 13697.726045478836, 13701.45194192261, 11876.0, 12120.454579543766, 12208.877911221129, 12363.0727695425, 12508.312042837731, 11916.761664766484, 12111.031117510402, 12483.812742808683, 12503.187292825936, 12589.874101259236, 11785.764284714429, 11882.108841836724, 11946.462409191434, 12360.938195309325, 12368.938155309166, 9481.810363792823, 9645.855312170463, 9761.511924403609, 9777.462239576891, 9978.60085596568, 8784.429012114193, 9175.311851611023, 9562.0, 9562.330636855353, 10571.841422378824], "value": 7439.041819133637, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1570.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 117.0, 116.0, 118.0, 116.0, 117.0, 117.0, 117.0, 124.0, 157.0], "value": 120.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 122.0, 115.0, 151.0, 115.0, 118.0, 115.0, 116.0, 145.0, 117.0], "value": 126.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 193.0, 209.0, 227.0, 261.0, 275.0, 195.0, 196.0, 191.0, 194.0], "value": 213.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 60.0, 85.0, 68.0, 84.0, 71.0, 58.0, 79.0, 59.0], "value": 70.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 70.0, 86.0, 72.0, 69.0, 70.0, 89.0, 72.0, 100.0, 115.0], "value": 82.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 85.0, 88.0, 85.0, 87.0, 85.0, 89.0, 89.0, 88.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 106.0, 137.0, 149.0, 99.0, 113.0, 125.0, 99.0, 98.0, 101.0], "value": 112.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 107.0, 128.0, 82.0, 133.0, 76.0, 138.0, 82.0, 134.0], "value": 105.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 57.0, 55.0, 57.0, 57.0, 58.0, 58.0, 58.0, 59.0, 58.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 80.0, 77.0, 79.0, 69.0, 69.0, 68.0, 71.0, 71.0], "value": 72.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 111.0, 108.0, 81.0, 88.0, 107.0, 109.0, 87.0, 90.0, 87.0], "value": 95.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 161.0, 160.0, 162.0, 150.0, 159.0, 145.0, 163.0, 266.0, 142.0], "value": 165.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 178.0, 171.0, 235.0, 213.0, 234.0, 188.0, 171.0, 174.0, 175.0], "value": 191.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 68.0, 70.0, 72.0, 69.0, 68.0, 70.0, 70.0, 68.0], "value": 69.5, "unit": "score"}]}]} 11:43:49 INFO - Return code: 0 11:43:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:43:49 ERROR - # TBPL SUCCESS # 11:43:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:43:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:43:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:43:49 INFO - 2016-10-19 11:43:49.257 screenresolution[1671:10814] starting screenresolution argv=screenresolution get 11:43:49 INFO - 2016-10-19 11:43:49.260 screenresolution[1671:10814] Display 0: 1600x1200x32@60 11:43:49 INFO - 2016-10-19 11:43:49.266 screenresolution[1672:10817] starting screenresolution argv=screenresolution list 11:43:49 INFO - Available Modes on Display 0 11:43:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:43:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:43:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:43:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:43:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:43:49 INFO - Intel Iris: 11:43:49 INFO - Chipset Model: Intel Iris 11:43:49 INFO - Type: GPU 11:43:49 INFO - Bus: Built-In 11:43:49 INFO - VRAM (Dynamic, Max): 1536 MB 11:43:49 INFO - Vendor: Intel (0x8086) 11:43:49 INFO - Device ID: 0x0a2e 11:43:49 INFO - Revision ID: 0x0009 11:43:49 INFO - Displays: 11:43:49 INFO - Display: 11:43:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:43:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:43:49 INFO - Display Serial Number: 1600x1200 60 11:43:49 INFO - Main Display: Yes 11:43:49 INFO - Mirror: Off 11:43:49 INFO - Online: Yes 11:43:49 INFO - Rotation: Supported 11:43:49 INFO - Return code: 0 11:43:49 INFO - Running post-action listener: _resource_record_post_action 11:43:49 INFO - [mozharness: 2016-10-19 18:43:49.500714Z] Finished run-tests step (success) 11:43:49 INFO - Running post-run listener: _resource_record_post_run 11:43:49 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 33202688; Write bytes: 709944832; Read time: 316; Write time: 2116 11:43:49 INFO - TinderboxPrint: CPU usage
54.7% 11:43:49 INFO - TinderboxPrint: I/O read bytes / time
33,202,688 / 316 11:43:49 INFO - TinderboxPrint: I/O write bytes / time
709,944,832 / 2,116 11:43:49 INFO - TinderboxPrint: CPU idle
2,193.0 (45.3%) 11:43:49 INFO - TinderboxPrint: CPU system
983.8 (20.3%) 11:43:49 INFO - TinderboxPrint: CPU user
1,668.9 (34.4%) 11:43:49 INFO - TinderboxPrint: Swap in / out
435,879,936 / 0 11:43:49 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 395411968; Write bytes: 390141952; Read time: 29366; Write time: 820 11:43:49 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 31379968; Write bytes: 299412992; Read time: 296; Write time: 1247 11:43:49 INFO - Running post-run listener: _upload_blobber_files 11:43:49 INFO - Blob upload gear active. 11:43:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:43:49 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 11:43:49 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'] 11:43:49 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 11:43:50 INFO - (blobuploader) - INFO - Open directory for files ... 11:43:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:43:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:43:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:51 INFO - (blobuploader) - INFO - Done attempting. 11:43:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:43:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:43:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:52 INFO - (blobuploader) - INFO - Done attempting. 11:43:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:43:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:43:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:43:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:43:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:43:54 INFO - (blobuploader) - INFO - Done attempting. 11:43:54 INFO - (blobuploader) - INFO - Iteration through files over. 11:43:54 INFO - Return code: 0 11:43:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:43:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:43:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65aadb1333f3b57ba185f0856a4b62d8d4d87d36f625c2e3dd065467d66d3ef67046e863daea43aa40f2fb06ff8abc05b429a5b3c1c2e7bdb3ebdd0ffb653af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c681b96996417b218de10cff7882642c6283f88002500c2b39320954c66251932c1ea8e6da153dc734e17bac769a7d9266e0fbf0fdc80ae8576b71a37c83712b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df5a685bb126d27a221eadd947c9275ee4a793049488a4d82da6f28dca7bbf57d5f9c6523e17b59a377545a055c76247c349ec690cce8fb7f9b5b2ba1cb4af3f"} 11:43:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:43:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:43:54 INFO - Contents: 11:43:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/65aadb1333f3b57ba185f0856a4b62d8d4d87d36f625c2e3dd065467d66d3ef67046e863daea43aa40f2fb06ff8abc05b429a5b3c1c2e7bdb3ebdd0ffb653af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c681b96996417b218de10cff7882642c6283f88002500c2b39320954c66251932c1ea8e6da153dc734e17bac769a7d9266e0fbf0fdc80ae8576b71a37c83712b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df5a685bb126d27a221eadd947c9275ee4a793049488a4d82da6f28dca7bbf57d5f9c6523e17b59a377545a055c76247c349ec690cce8fb7f9b5b2ba1cb4af3f"} 11:43:54 INFO - Running post-run listener: copy_logs_to_upload_dir 11:43:54 INFO - Copying logs to upload dir... 11:43:54 INFO - mkdir: /builds/slave/test/build/upload/logs 11:43:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.376207 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-19 11:43:54.974753) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 11:43:54.977913) ========= 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.UyltK14vOy/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.mrekMM8cRA/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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/65aadb1333f3b57ba185f0856a4b62d8d4d87d36f625c2e3dd065467d66d3ef67046e863daea43aa40f2fb06ff8abc05b429a5b3c1c2e7bdb3ebdd0ffb653af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c681b96996417b218de10cff7882642c6283f88002500c2b39320954c66251932c1ea8e6da153dc734e17bac769a7d9266e0fbf0fdc80ae8576b71a37c83712b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df5a685bb126d27a221eadd947c9275ee4a793049488a4d82da6f28dca7bbf57d5f9c6523e17b59a377545a055c76247c349ec690cce8fb7f9b5b2ba1cb4af3f"} build_url:https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/DriP1iVDRseUABNTgPjtJg/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/65aadb1333f3b57ba185f0856a4b62d8d4d87d36f625c2e3dd065467d66d3ef67046e863daea43aa40f2fb06ff8abc05b429a5b3c1c2e7bdb3ebdd0ffb653af0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c681b96996417b218de10cff7882642c6283f88002500c2b39320954c66251932c1ea8e6da153dc734e17bac769a7d9266e0fbf0fdc80ae8576b71a37c83712b", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/df5a685bb126d27a221eadd947c9275ee4a793049488a4d82da6f28dca7bbf57d5f9c6523e17b59a377545a055c76247c349ec690cce8fb7f9b5b2ba1cb4af3f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 11:43:55.009836) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:43:55.010150) ========= 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.UyltK14vOy/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.mrekMM8cRA/Listeners TMPDIR=/var/folders/hx/wyz88y_j2rv2m160wr6142w000000w/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.005097 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 11:43:55.058604) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 11:43:55.058939) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 11:43:55.059255) ========= ========= Total master_lag: 1.33 =========