builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0098 starttime: 1476792646.72 results: success (0) buildid: 20161018014648 builduid: 7b908df9ca3a416793d26a82430b66c5 revision: d87df32f5c1f5565de9e7e6108ea1d47c410d6f8 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.720731) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.721154) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.721442) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.745765) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.746061) ========= 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.ICFvWuDl3a/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.tneSoEpEjf/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-18 05:10:46-- 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% 9.70M=0.001s 2016-10-18 05:10:46 (9.70 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.102489 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.871257) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.871625) ========= 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.ICFvWuDl3a/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.tneSoEpEjf/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.026257 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:46.920370) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 05:10:46.920713) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev d87df32f5c1f5565de9e7e6108ea1d47c410d6f8 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev d87df32f5c1f5565de9e7e6108ea1d47c410d6f8 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ICFvWuDl3a/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.tneSoEpEjf/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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-18 05:10:46,992 truncating revision to first 12 chars 2016-10-18 05:10:46,992 Setting DEBUG logging. 2016-10-18 05:10:46,992 attempt 1/10 2016-10-18 05:10:46,992 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/d87df32f5c1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-18 05:10:48,340 unpacking tar archive at: autoland-d87df32f5c1f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.599633 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-18 05:10:48.542204) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:48.542559) ========= script_repo_revision: d87df32f5c1f5565de9e7e6108ea1d47c410d6f8 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:48.543007) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:48.543335) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-18 05:10:48.561652) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-18 05:10:48.562049) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ICFvWuDl3a/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.tneSoEpEjf/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 05:10:48 INFO - MultiFileLogger online at 20161018 05:10:48 in /builds/slave/test 05:10:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 05:10:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:10:48 INFO - {'append_to_log': False, 05:10:48 INFO - 'base_work_dir': '/builds/slave/test', 05:10:48 INFO - 'blob_upload_branch': 'Autoland', 05:10:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:10:48 INFO - 'branch': 'Autoland', 05:10:48 INFO - 'buildbot_json_path': 'buildprops.json', 05:10:48 INFO - 'config_files': ('talos/mac_config.py',), 05:10:48 INFO - 'default_actions': ('clobber', 05:10:48 INFO - 'read-buildbot-config', 05:10:48 INFO - 'download-and-extract', 05:10:48 INFO - 'populate-webroot', 05:10:48 INFO - 'create-virtualenv', 05:10:48 INFO - 'install', 05:10:48 INFO - 'run-tests'), 05:10:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:10:48 INFO - 'download_minidump_stackwalk': True, 05:10:48 INFO - 'download_symbols': 'ondemand', 05:10:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:10:48 INFO - 'tooltool.py': '/tools/tooltool.py', 05:10:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:10:48 INFO - '/tools/misc-python/virtualenv.py')}, 05:10:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:10:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:10:48 INFO - 'installer_path': 'installer.exe', 05:10:48 INFO - 'log_level': 'info', 05:10:48 INFO - 'log_name': 'talos', 05:10:48 INFO - 'log_to_console': True, 05:10:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:10:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:10:48 INFO - 'opt_config_files': (), 05:10:48 INFO - 'pip_index': False, 05:10:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:48 INFO - 'cmd': ('bash', 05:10:48 INFO - '-c', 05:10:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:48 INFO - 'enabled': True, 05:10:48 INFO - 'halt_on_failure': False, 05:10:48 INFO - 'name': 'check_screen_resolution'},), 05:10:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:10:48 INFO - 'cmd': ('bash', 05:10:48 INFO - '-c', 05:10:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:10:48 INFO - 'enabled': True, 05:10:48 INFO - 'halt_on_failure': False, 05:10:48 INFO - 'name': 'check_screen_resolution'},), 05:10:48 INFO - 'run_cmd_checks_enabled': True, 05:10:48 INFO - 'sps_profile': False, 05:10:48 INFO - 'sps_profile_interval': 0, 05:10:48 INFO - 'suite': 'dromaeojs-e10s', 05:10:48 INFO - 'system_bits': '32', 05:10:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:10:48 INFO - 'title': 't-yosemite-r7-0098', 05:10:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:10:48 INFO - 'use_talos_json': True, 05:10:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:10:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:10:48 INFO - 'work_dir': 'build'} 05:10:48 INFO - [mozharness: 2016-10-18 12:10:48.761419Z] Running clobber step. 05:10:48 INFO - Running pre-action listener: _resource_record_pre_action 05:10:48 INFO - Running main action method: clobber 05:10:48 INFO - rmtree: /builds/slave/test/build 05:10:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:10:50 INFO - Running post-action listener: _resource_record_post_action 05:10:50 INFO - [mozharness: 2016-10-18 12:10:50.920294Z] Finished clobber step (success) 05:10:50 INFO - [mozharness: 2016-10-18 12:10:50.920474Z] Running read-buildbot-config step. 05:10:50 INFO - Running pre-action listener: _resource_record_pre_action 05:10:50 INFO - Running main action method: read_buildbot_config 05:10:50 INFO - Using buildbot properties: 05:10:50 INFO - { 05:10:50 INFO - "project": "", 05:10:50 INFO - "product": "firefox", 05:10:50 INFO - "script_repo_revision": "production", 05:10:50 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 05:10:50 INFO - "repository": "", 05:10:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 05:10:50 INFO - "buildid": "20161018014648", 05:10:50 INFO - "pgo_build": "False", 05:10:50 INFO - "basedir": "/builds/slave/test", 05:10:50 INFO - "buildnumber": 49, 05:10:50 INFO - "slavename": "t-yosemite-r7-0098", 05:10:50 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:10:50 INFO - "platform": "yosemite_r7", 05:10:50 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 05:10:50 INFO - "branch": "autoland", 05:10:50 INFO - "repo_path": "integration/autoland", 05:10:50 INFO - "revision": "d87df32f5c1f5565de9e7e6108ea1d47c410d6f8", 05:10:50 INFO - "stage_platform": "macosx64", 05:10:50 INFO - "builduid": "7b908df9ca3a416793d26a82430b66c5", 05:10:50 INFO - "slavebuilddir": "test" 05:10:50 INFO - } 05:10:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 05:10:50 INFO - Running post-action listener: _resource_record_post_action 05:10:50 INFO - [mozharness: 2016-10-18 12:10:50.923848Z] Finished read-buildbot-config step (success) 05:10:50 INFO - [mozharness: 2016-10-18 12:10:50.923936Z] Running download-and-extract step. 05:10:50 INFO - Running pre-action listener: _resource_record_pre_action 05:10:50 INFO - Running main action method: download_and_extract 05:10:50 INFO - mkdir: /builds/slave/test/build/tests 05:10:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:10:50 INFO - trying https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 05:10:50 INFO - Downloading https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:10:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 05:10:52 INFO - Downloaded 1199 bytes. 05:10:52 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 05:10:52 INFO - Using the following test package requirements: 05:10:52 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:10:52 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 05:10:52 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 05:10:52 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'jsshell-mac.zip'], 05:10:52 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 05:10:52 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 05:10:52 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 05:10:52 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 05:10:52 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 05:10:52 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 05:10:52 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 05:10:52 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:10:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:10:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:10:52 INFO - Fetch https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:10:52 INFO - Http code: 200 05:10:52 INFO - via: 1.1 55676da1e5c0a9c4e60a94a95b01dc04.cloudfront.net (CloudFront) 05:10:52 INFO - x-amz-cf-id: UoWiiICt16g-HOUm37-zrDTPcRPfTIuTx4D-j6sXGMTQYF67W_JmLQ== 05:10:52 INFO - x-amz-version-id: 5nN7pNTwdNvnCvyZSUfNWnZlnFzvtu48 05:10:52 INFO - Expected file size: 16483609 05:10:52 INFO - Obtained file size: 16483609 05:10:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 05:10:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 05:10:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 05:10:59 INFO - Fetch https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 05:11:00 INFO - Http code: 200 05:11:00 INFO - via: 1.1 dc7c4fb5024ff022cad1642ec506a6e8.cloudfront.net (CloudFront) 05:11:00 INFO - x-amz-cf-id: O_iQFQGBxMdffrlteFOYscT6wZOmuZ3i91jjMMjM2QbsXElJSi3M-Q== 05:11:00 INFO - x-amz-version-id: 5nN7pNTwdNvnCvyZSUfNWnZlnFzvtu48 05:11:00 INFO - Expected file size: 16483609 05:11:00 INFO - Obtained file size: 16483609 05:11:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 05:11:06 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 05:11:06 INFO - Fetch https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 05:11:07 INFO - Http code: 200 05:11:07 INFO - via: 1.1 3d3d633d266d05d90a4eea7a6a59b514.cloudfront.net (CloudFront) 05:11:07 INFO - x-amz-cf-id: gqsN1WQeWXP8l89-G6K3Ugu9ysMWW8xh5tQb4boAdLFp3zKP_OVTtA== 05:11:07 INFO - x-amz-version-id: K5o8h68PQQdvSuyy1lWljTXSh8PKwfht 05:11:07 INFO - Expected file size: 14409028 05:11:07 INFO - Obtained file size: 14409028 05:11: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')]} 05:11:08 INFO - trying https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:11:08 INFO - Downloading https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 05:11:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 05:11:11 INFO - Downloaded 111619237 bytes. 05:11:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:11:11 INFO - mkdir: /builds/slave/test/properties 05:11:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:11:11 INFO - Writing to file /builds/slave/test/properties/build_url 05:11:11 INFO - Contents: 05:11:11 INFO - build_url:https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 05:11:11 INFO - Running post-action listener: _resource_record_post_action 05:11:11 INFO - Running post-action listener: set_extra_try_arguments 05:11:11 INFO - [mozharness: 2016-10-18 12:11:11.712097Z] Finished download-and-extract step (success) 05:11:11 INFO - [mozharness: 2016-10-18 12:11:11.712240Z] Running populate-webroot step. 05:11:11 INFO - Running pre-action listener: _resource_record_pre_action 05:11:11 INFO - Running main action method: populate_webroot 05:11:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:11:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:11:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:11:11 INFO - '--tppagecycles', 05:11:11 INFO - '7'], 05:11:11 INFO - 'tests': ['tsvgm']}}, 05:11:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:11:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:11:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:11:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:11:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:11:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['damp', 'tps']}, 05:11:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'tests': ['damp', 'tps']}, 05:11:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['dromaeo_dom']}, 05:11:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:11:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['basic_compositor_video']}, 05:11:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:11:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['a11yr', 05:11:11 INFO - 'ts_paint', 05:11:11 INFO - 'tpaint', 05:11:11 INFO - 'sessionrestore', 05:11:11 INFO - 'sessionrestore_no_auto_restore', 05:11:11 INFO - 'tabpaint']}, 05:11:11 INFO - 'other-e10s': {'tests': ['a11yr', 05:11:11 INFO - 'ts_paint', 05:11:11 INFO - 'tpaint', 05:11:11 INFO - 'sessionrestore', 05:11:11 INFO - 'sessionrestore_no_auto_restore', 05:11:11 INFO - 'tabpaint']}, 05:11:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:11:11 INFO - 'ts_paint', 05:11:11 INFO - 'tpaint', 05:11:11 INFO - 'sessionrestore', 05:11:11 INFO - 'sessionrestore_no_auto_restore', 05:11:11 INFO - 'tabpaint']}, 05:11:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:11:11 INFO - 'ts_paint', 05:11:11 INFO - 'tpaint', 05:11:11 INFO - 'sessionrestore', 05:11:11 INFO - 'sessionrestore_no_auto_restore', 05:11:11 INFO - 'tabpaint']}, 05:11:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['a11yr', 05:11:11 INFO - 'ts_paint', 05:11:11 INFO - 'tpaint', 05:11:11 INFO - 'sessionrestore', 05:11:11 INFO - 'sessionrestore_no_auto_restore', 05:11:11 INFO - 'tabpaint']}, 05:11:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['a11yr', 05:11:11 INFO - 'ts_paint', 05:11:11 INFO - 'tpaint', 05:11:11 INFO - 'sessionrestore', 05:11:11 INFO - 'sessionrestore_no_auto_restore', 05:11:11 INFO - 'tabpaint']}, 05:11:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['tsvgx', 05:11:11 INFO - 'tsvgr_opacity', 05:11:11 INFO - 'tart', 05:11:11 INFO - 'tscrollx', 05:11:11 INFO - 'cart']}, 05:11:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:11:11 INFO - 'tsvgr_opacity', 05:11:11 INFO - 'tart', 05:11:11 INFO - 'tscrollx', 05:11:11 INFO - 'cart']}, 05:11:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'talos_options': ['--disable-e10s'], 05:11:11 INFO - 'tests': ['tp5o']}, 05:11:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'tests': ['tp5o']}, 05:11:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'talos_options': ['--disable-e10s', 05:11:11 INFO - '--xperf_path', 05:11:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:11 INFO - 'tests': ['tp5n']}, 05:11:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:11:11 INFO - 'talos_options': ['--xperf_path', 05:11:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:11:11 INFO - 'tests': ['tp5n']}}, 05:11:11 INFO - 'talos.zip': {'path': '', 05:11:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:11:11 INFO - Running post-action listener: _resource_record_post_action 05:11:11 INFO - [mozharness: 2016-10-18 12:11:11.731032Z] Finished populate-webroot step (success) 05:11:11 INFO - [mozharness: 2016-10-18 12:11:11.731165Z] Running create-virtualenv step. 05:11:11 INFO - Running pre-action listener: _resource_record_pre_action 05:11:11 INFO - Running main action method: create_virtualenv 05:11:11 INFO - Creating virtualenv /builds/slave/test/build/venv 05:11:11 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:11:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:11:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:11:11 INFO - Using real prefix '/tools/python27' 05:11:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:11:12 INFO - Installing distribute.............................................................................................................................................................................................done. 05:11:15 INFO - Installing pip.................done. 05:11:15 INFO - Return code: 0 05:11:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:11:15 INFO - Reading from file tmpfile_stdout 05:11:15 INFO - Output received: 05:11:15 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:11:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:15 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:15 INFO - 'HOME': '/Users/cltbld', 05:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:15 INFO - 'LOGNAME': 'cltbld', 05:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:15 INFO - 'MOZ_NO_REMOTE': '1', 05:11:15 INFO - 'NO_EM_RESTART': '1', 05:11:15 INFO - 'PAGER': '/bin/cat', 05:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:15 INFO - 'PWD': '/builds/slave/test', 05:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:15 INFO - 'SHELL': '/bin/bash', 05:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:15 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:15 INFO - 'USER': 'cltbld', 05:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:15 INFO - 'XPC_FLAGS': '0x0', 05:11:15 INFO - 'XPC_SERVICE_NAME': '0', 05:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:15 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:11:15 INFO - Cleaning up... 05:11:15 INFO - Return code: 0 05:11:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:15 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:11:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:11:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:15 INFO - 'HOME': '/Users/cltbld', 05:11:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:15 INFO - 'LOGNAME': 'cltbld', 05:11:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:15 INFO - 'MOZ_NO_REMOTE': '1', 05:11:15 INFO - 'NO_EM_RESTART': '1', 05:11:15 INFO - 'PAGER': '/bin/cat', 05:11:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:15 INFO - 'PWD': '/builds/slave/test', 05:11:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:15 INFO - 'SHELL': '/bin/bash', 05:11:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:15 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:15 INFO - 'USER': 'cltbld', 05:11:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:15 INFO - 'XPC_FLAGS': '0x0', 05:11:15 INFO - 'XPC_SERVICE_NAME': '0', 05:11:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:16 INFO - Downloading/unpacking psutil>=3.1.1 05:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:11:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:19 INFO - Installing collected packages: psutil 05:11:19 INFO - Running setup.py install for psutil 05:11:19 INFO - building 'psutil._psutil_osx' extension 05:11:19 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 05:11:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:11:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:11:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:11:20 INFO - building 'psutil._psutil_posix' extension 05:11:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:11:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:11:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:11:20 INFO - ^ 05:11:20 INFO - 1 warning generated. 05:11:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:11:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:11:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:11:20 INFO - Successfully installed psutil 05:11:20 INFO - Cleaning up... 05:11:20 INFO - Return code: 0 05:11:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:11: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')]} 05:11:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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')]} 05:11:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:11: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 mozsystemmonitor==0.3 05:11:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:20 INFO - 'HOME': '/Users/cltbld', 05:11:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:20 INFO - 'LOGNAME': 'cltbld', 05:11:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:20 INFO - 'MOZ_NO_REMOTE': '1', 05:11:20 INFO - 'NO_EM_RESTART': '1', 05:11:20 INFO - 'PAGER': '/bin/cat', 05:11:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:20 INFO - 'PWD': '/builds/slave/test', 05:11:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:20 INFO - 'SHELL': '/bin/bash', 05:11:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:20 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:20 INFO - 'USER': 'cltbld', 05:11:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:20 INFO - 'XPC_FLAGS': '0x0', 05:11:20 INFO - 'XPC_SERVICE_NAME': '0', 05:11:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:11:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:11:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:11:23 INFO - Installing collected packages: mozsystemmonitor 05:11:23 INFO - Running setup.py install for mozsystemmonitor 05:11:23 INFO - Successfully installed mozsystemmonitor 05:11:23 INFO - Cleaning up... 05:11:23 INFO - Return code: 0 05:11:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:23 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:11:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:11:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:23 INFO - 'HOME': '/Users/cltbld', 05:11:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:23 INFO - 'LOGNAME': 'cltbld', 05:11:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:23 INFO - 'MOZ_NO_REMOTE': '1', 05:11:23 INFO - 'NO_EM_RESTART': '1', 05:11:23 INFO - 'PAGER': '/bin/cat', 05:11:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:23 INFO - 'PWD': '/builds/slave/test', 05:11:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:23 INFO - 'SHELL': '/bin/bash', 05:11:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:23 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:23 INFO - 'USER': 'cltbld', 05:11:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:23 INFO - 'XPC_FLAGS': '0x0', 05:11:23 INFO - 'XPC_SERVICE_NAME': '0', 05:11:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:24 INFO - Downloading/unpacking jsonschema==2.5.1 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11: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 05:11:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:11:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:27 INFO - Downloading functools32-3.2.3-2.tar.gz 05:11:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:11:27 INFO - warning: no files found matching '*.txt' 05:11:27 INFO - no previously-included directories found matching 'build' 05:11:27 INFO - no previously-included directories found matching 'dist' 05:11:27 INFO - no previously-included directories found matching '.git*' 05:11:27 INFO - Installing collected packages: jsonschema, functools32 05:11:27 INFO - Running setup.py install for functools32 05:11:27 INFO - warning: no files found matching '*.txt' 05:11:27 INFO - no previously-included directories found matching 'build' 05:11:27 INFO - no previously-included directories found matching 'dist' 05:11:27 INFO - no previously-included directories found matching '.git*' 05:11:27 INFO - Successfully installed jsonschema functools32 05:11:27 INFO - Cleaning up... 05:11:27 INFO - Return code: 0 05:11:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:27 INFO - 'HOME': '/Users/cltbld', 05:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:27 INFO - 'LOGNAME': 'cltbld', 05:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:27 INFO - 'MOZ_NO_REMOTE': '1', 05:11:27 INFO - 'NO_EM_RESTART': '1', 05:11:27 INFO - 'PAGER': '/bin/cat', 05:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:27 INFO - 'PWD': '/builds/slave/test', 05:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:27 INFO - 'SHELL': '/bin/bash', 05:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:27 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:27 INFO - 'USER': 'cltbld', 05:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:27 INFO - 'XPC_FLAGS': '0x0', 05:11:27 INFO - 'XPC_SERVICE_NAME': '0', 05:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:11:27 INFO - Cleaning up... 05:11:27 INFO - Return code: 0 05:11:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:27 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:11:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:11:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:27 INFO - 'HOME': '/Users/cltbld', 05:11:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:27 INFO - 'LOGNAME': 'cltbld', 05:11:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:27 INFO - 'MOZ_NO_REMOTE': '1', 05:11:27 INFO - 'NO_EM_RESTART': '1', 05:11:27 INFO - 'PAGER': '/bin/cat', 05:11:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:27 INFO - 'PWD': '/builds/slave/test', 05:11:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:27 INFO - 'SHELL': '/bin/bash', 05:11:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:27 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:27 INFO - 'USER': 'cltbld', 05:11:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:27 INFO - 'XPC_FLAGS': '0x0', 05:11:27 INFO - 'XPC_SERVICE_NAME': '0', 05:11:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:28 INFO - Downloading/unpacking blobuploader==1.2.4 05:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:30 INFO - Downloading blobuploader-1.2.4.tar.gz 05:11:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:11:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:11:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:31 INFO - Downloading docopt-0.6.1.tar.gz 05:11:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:11:31 INFO - Installing collected packages: blobuploader, requests, docopt 05:11:31 INFO - Running setup.py install for blobuploader 05:11:32 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:11:32 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:11:32 INFO - Running setup.py install for requests 05:11:32 INFO - Running setup.py install for docopt 05:11:32 INFO - Successfully installed blobuploader requests docopt 05:11:32 INFO - Cleaning up... 05:11:32 INFO - Return code: 0 05:11:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:32 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:11:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:11:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:32 INFO - 'HOME': '/Users/cltbld', 05:11:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:32 INFO - 'LOGNAME': 'cltbld', 05:11:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:32 INFO - 'MOZ_NO_REMOTE': '1', 05:11:32 INFO - 'NO_EM_RESTART': '1', 05:11:32 INFO - 'PAGER': '/bin/cat', 05:11:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:32 INFO - 'PWD': '/builds/slave/test', 05:11:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:32 INFO - 'SHELL': '/bin/bash', 05:11:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:32 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:32 INFO - 'USER': 'cltbld', 05:11:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:32 INFO - 'XPC_FLAGS': '0x0', 05:11:32 INFO - 'XPC_SERVICE_NAME': '0', 05:11:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-z_gfxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-1WyQJ2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-b4_hc_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-PEDGW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Z8Fbm_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-faqV9r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-quAB00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ZcPZrN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:33 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-EJyiU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-bWH377-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-MnXgja-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-f3tQNr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-ewbEyj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pSOEuI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9TEVqa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-n39456-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:34 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-_taoYf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:11:34 INFO - Running setup.py install for manifestparser 05:11:35 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:11:35 INFO - Running setup.py install for mozcrash 05:11:35 INFO - Running setup.py install for mozdebug 05:11:35 INFO - Running setup.py install for mozdevice 05:11:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:11:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:11:35 INFO - Running setup.py install for mozfile 05:11:35 INFO - Running setup.py install for mozhttpd 05:11:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:11:35 INFO - Running setup.py install for mozinfo 05:11:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:11:35 INFO - Running setup.py install for mozInstall 05:11:36 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Running setup.py install for mozleak 05:11:36 INFO - Running setup.py install for mozlog 05:11:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Running setup.py install for moznetwork 05:11:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Running setup.py install for mozprocess 05:11:36 INFO - Running setup.py install for mozprofile 05:11:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:11:36 INFO - Running setup.py install for mozrunner 05:11:37 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:11:37 INFO - Running setup.py install for mozscreenshot 05:11:37 INFO - Running setup.py install for moztest 05:11:37 INFO - Running setup.py install for mozversion 05:11:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:11:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:11:37 INFO - Cleaning up... 05:11:37 INFO - Return code: 0 05:11:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:37 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:11:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:11:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:37 INFO - 'HOME': '/Users/cltbld', 05:11:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:37 INFO - 'LOGNAME': 'cltbld', 05:11:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:37 INFO - 'MOZ_NO_REMOTE': '1', 05:11:37 INFO - 'NO_EM_RESTART': '1', 05:11:37 INFO - 'PAGER': '/bin/cat', 05:11:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:37 INFO - 'PWD': '/builds/slave/test', 05:11:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:37 INFO - 'SHELL': '/bin/bash', 05:11:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:37 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:37 INFO - 'USER': 'cltbld', 05:11:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:37 INFO - 'XPC_FLAGS': '0x0', 05:11:37 INFO - 'XPC_SERVICE_NAME': '0', 05:11:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:11:37 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-FcMxUI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:11:37 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-2sSBpR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:11:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:11:37 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-GTCXw_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-dm0mLT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-B28kHa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-TxEqak-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-9OkDkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-KEsnkv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-rz5cRp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-x9Aw2I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-Eam6K9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:11:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:11:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:11:38 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-dGgvs4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 05:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:11:39 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-owDW6U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:11:39 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-dhNb5B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 05:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:39 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-pQS58L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:11:39 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-JjAoS3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 05:11:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:11:39 INFO - Running setup.py (path:/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/pip-m1qNZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 05:11:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:11:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:11:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:11:41 INFO - Downloading blessings-1.6.tar.gz 05:11:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:11:42 INFO - Installing collected packages: blessings 05:11:42 INFO - Running setup.py install for blessings 05:11:42 INFO - Successfully installed blessings 05:11:42 INFO - Cleaning up... 05:11:42 INFO - Return code: 0 05:11:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:11:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:42 INFO - Reading from file tmpfile_stdout 05:11:42 INFO - Current package versions: 05:11:42 INFO - blessings == 1.6 05:11:42 INFO - blobuploader == 1.2.4 05:11:42 INFO - docopt == 0.6.1 05:11:42 INFO - functools32 == 3.2.3-2 05:11:42 INFO - jsonschema == 2.5.1 05:11:42 INFO - manifestparser == 1.1 05:11:42 INFO - mozInstall == 1.12 05:11:42 INFO - mozcrash == 0.17 05:11:42 INFO - mozdebug == 0.1 05:11:42 INFO - mozdevice == 0.48 05:11:42 INFO - mozfile == 1.2 05:11:42 INFO - mozhttpd == 0.7 05:11:42 INFO - mozinfo == 0.9 05:11:42 INFO - mozleak == 0.1 05:11:42 INFO - mozlog == 3.4 05:11:42 INFO - moznetwork == 0.27 05:11:42 INFO - mozprocess == 0.23 05:11:42 INFO - mozprofile == 0.28 05:11:42 INFO - mozrunner == 6.12 05:11:42 INFO - mozscreenshot == 0.1 05:11:42 INFO - mozsystemmonitor == 0.3 05:11:42 INFO - moztest == 0.7 05:11:42 INFO - mozversion == 1.4 05:11:42 INFO - psutil == 3.1.1 05:11:42 INFO - requests == 1.2.3 05:11:42 INFO - wsgiref == 0.1.2 05:11:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:42 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 05:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:42 INFO - 'HOME': '/Users/cltbld', 05:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:42 INFO - 'LOGNAME': 'cltbld', 05:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:42 INFO - 'MOZ_NO_REMOTE': '1', 05:11:42 INFO - 'NO_EM_RESTART': '1', 05:11:42 INFO - 'PAGER': '/bin/cat', 05:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:42 INFO - 'PWD': '/builds/slave/test', 05:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:42 INFO - 'SHELL': '/bin/bash', 05:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:42 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:42 INFO - 'USER': 'cltbld', 05:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:42 INFO - 'XPC_FLAGS': '0x0', 05:11:42 INFO - 'XPC_SERVICE_NAME': '0', 05:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 05:11:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 05:11:42 INFO - Cleaning up... 05:11:42 INFO - Return code: 0 05:11:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:11:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:11:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:11:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:11:42 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 0x1073fc490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107372570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc5c3d8bb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, '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.ICFvWuDl3a/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.tneSoEpEjf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 05:11:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 05:11:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 05:11:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:11:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:11:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:11:42 INFO - 'HOME': '/Users/cltbld', 05:11:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:11:42 INFO - 'LOGNAME': 'cltbld', 05:11:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:11:42 INFO - 'MOZ_NO_REMOTE': '1', 05:11:42 INFO - 'NO_EM_RESTART': '1', 05:11:42 INFO - 'PAGER': '/bin/cat', 05:11:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:11:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:11:42 INFO - 'PWD': '/builds/slave/test', 05:11:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:11:42 INFO - 'SHELL': '/bin/bash', 05:11:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:11:42 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:11:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:11:42 INFO - 'USER': 'cltbld', 05:11:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:11:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:11:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:11:42 INFO - 'XPC_FLAGS': '0x0', 05:11:42 INFO - 'XPC_SERVICE_NAME': '0', 05:11:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:11:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:11:43 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:11:43 INFO - Cleaning up... 05:11:43 INFO - Return code: 0 05:11:43 INFO - Running post-action listener: _resource_record_post_action 05:11:43 INFO - Running post-action listener: _start_resource_monitoring 05:11:43 INFO - Starting resource monitoring. 05:11:43 INFO - [mozharness: 2016-10-18 12:11:43.185281Z] Finished create-virtualenv step (success) 05:11:43 INFO - [mozharness: 2016-10-18 12:11:43.186254Z] Running install step. 05:11:43 INFO - Running pre-action listener: _resource_record_pre_action 05:11:43 INFO - Running main action method: install 05:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:11:43 INFO - Reading from file tmpfile_stdout 05:11:43 INFO - Detecting whether we're running mozinstall >=1.0... 05:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:11:43 INFO - Reading from file tmpfile_stdout 05:11:43 INFO - Output received: 05:11:43 INFO - Usage: mozinstall [options] installer 05:11:43 INFO - Options: 05:11:43 INFO - -h, --help show this help message and exit 05:11:43 INFO - -d DEST, --destination=DEST 05:11:43 INFO - Directory to install application into. [default: 05:11:43 INFO - "/builds/slave/test"] 05:11:43 INFO - --app=APP Application being installed. [default: firefox] 05:11:43 INFO - mkdir: /builds/slave/test/build/application 05:11:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:11:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 05:12:17 INFO - Reading from file tmpfile_stdout 05:12:17 INFO - Output received: 05:12:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:12:17 INFO - Running post-action listener: _resource_record_post_action 05:12:17 INFO - [mozharness: 2016-10-18 12:12:17.419382Z] Finished install step (success) 05:12:17 INFO - [mozharness: 2016-10-18 12:12:17.419545Z] Running run-tests step. 05:12:17 INFO - Running pre-action listener: _resource_record_pre_action 05:12:17 INFO - Running main action method: run_tests 05:12:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:12:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:12:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:12:17 INFO - 2016-10-18 05:12:17.433 screenresolution[1636:7504] starting screenresolution argv=screenresolution get 05:12:17 INFO - 2016-10-18 05:12:17.436 screenresolution[1636:7504] Display 0: 1600x1200x32@60 05:12:17 INFO - 2016-10-18 05:12:17.442 screenresolution[1637:7506] starting screenresolution argv=screenresolution list 05:12:17 INFO - Available Modes on Display 0 05:12:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:12:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:12:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:12:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:12:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:12:17 INFO - Intel Iris: 05:12:17 INFO - Chipset Model: Intel Iris 05:12:17 INFO - Type: GPU 05:12:17 INFO - Bus: Built-In 05:12:17 INFO - VRAM (Dynamic, Max): 1536 MB 05:12:17 INFO - Vendor: Intel (0x8086) 05:12:17 INFO - Device ID: 0x0a2e 05:12:17 INFO - Revision ID: 0x0009 05:12:17 INFO - Displays: 05:12:17 INFO - Display: 05:12:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:12:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:12:17 INFO - Display Serial Number: 1600x1200 60 05:12:17 INFO - Main Display: Yes 05:12:17 INFO - Mirror: Off 05:12:17 INFO - Online: Yes 05:12:17 INFO - Rotation: Supported 05:12:17 INFO - Return code: 0 05:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:12:17 INFO - Python 2.7.3 05:12:17 INFO - Return code: 0 05:12:17 INFO - grabbing minidump binary from tooltool 05:12: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')]} 05:12:17 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 0x107210d30>, '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 0x107036db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107208b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:12:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 05:12:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 05:12:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 05:12:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:12:17 INFO - Return code: 0 05:12:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:12:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:12:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:12:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:12:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:12:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 05:12:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0098 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 05:12:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ICFvWuDl3a/Render', 05:12:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:12:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:12:17 INFO - 'HOME': '/Users/cltbld', 05:12:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:12:17 INFO - 'LOGNAME': 'cltbld', 05:12:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:12:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:12:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:12:17 INFO - 'MOZ_NO_REMOTE': '1', 05:12:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:12:17 INFO - 'NO_EM_RESTART': '1', 05:12:17 INFO - 'PAGER': '/bin/cat', 05:12:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:12:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:12:17 INFO - 'PWD': '/builds/slave/test', 05:12:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:12:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:12:17 INFO - 'SHELL': '/bin/bash', 05:12:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.tneSoEpEjf/Listeners', 05:12:17 INFO - 'TMPDIR': '/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/', 05:12:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:12:17 INFO - 'USER': 'cltbld', 05:12:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:12:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:12:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:12:17 INFO - 'XPC_FLAGS': '0x0', 05:12:17 INFO - 'XPC_SERVICE_NAME': '0', 05:12:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:12:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0098', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 05:12:18 INFO - mozversion application_buildid: 20161018014648 05:12:18 INFO - mozversion application_changeset: d87df32f5c1f5565de9e7e6108ea1d47c410d6f8 05:12:18 INFO - mozversion application_display_name: Nightly 05:12:18 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:12:18 INFO - mozversion application_name: Firefox 05:12:18 INFO - mozversion application_remotingname: firefox 05:12:18 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 05:12:18 INFO - mozversion application_vendor: Mozilla 05:12:18 INFO - mozversion application_version: 52.0a1 05:12:18 INFO - mozversion platform_buildid: 20161018014648 05:12:18 INFO - mozversion platform_changeset: d87df32f5c1f5565de9e7e6108ea1d47c410d6f8 05:12:18 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 05:12:18 INFO - mozversion platform_version: 52.0a1 05:12:18 INFO - using testdate: 1476792738 05:12:18 INFO - actual date: 1476792738 05:12:18 INFO - starting webserver on 'localhost:49266' 05:12:18 INFO - SUITE-START | Running 2 tests 05:12:18 INFO - TEST-START | dromaeo_css 05:12:18 INFO - operating with platform_type : mac_ 05:12:18 INFO - Initialising browser for dromaeo_css test... 05:12:18 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpkzOd4G/profile http://localhost:49266/getInfo.html) 05:12:20 INFO - PROCESS | 1647 | 2016-10-18 05:12:20.841 plugin-container[1649:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c3f, name = 'com.apple.tsm.portname' 05:12:20 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:20 INFO - PROCESS | 1647 | 2016-10-18 05:12:20.846 plugin-container[1649:7688] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910b, name = 'com.apple.CFPasteboardClient' 05:12:20 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:20 INFO - PROCESS | 1647 | 2016-10-18 05:12:20.846 plugin-container[1649:7688] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:12:20 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:12:20 INFO - PROCESS | 1647 | colorDepth:24 05:12:20 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:12:20 INFO - PROCESS | 1647 | __metrics 05:12:21 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 05:12:21 INFO - TEST-INFO | 1647: exit 0 05:12:21 INFO - Browser initialized. 05:12:21 INFO - Running cycle 1/1 for dromaeo_css test... 05:12:21 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpkzOd4G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:12:24 INFO - PROCESS | 1650 | 2016-10-18 05:12:24.283 plugin-container[1651:7803] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 05:12:24 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:24 INFO - PROCESS | 1650 | 2016-10-18 05:12:24.284 plugin-container[1651:7803] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:12:24 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:12:24 INFO - PROCESS | 1650 | 2016-10-18 05:12:24.284 plugin-container[1651:7803] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:12:33 INFO - PROCESS | 1650 | RSS: Main: 236638208 05:12:33 INFO - PROCESS | 1650 | 05:13:23 INFO - PROCESS | 1650 | 1476792803607 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 05:15:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:15:38 INFO - PROCESS | 1650 | RSS: Main: 250212352 05:15:38 INFO - PROCESS | 1650 | 05:18:41 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:18:41 INFO - PROCESS | 1650 | RSS: Main: 264646656 05:18:41 INFO - PROCESS | 1650 | 05:21:43 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:21:44 INFO - PROCESS | 1650 | RSS: Main: 269021184 05:21:44 INFO - PROCESS | 1650 | 05:24:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:24:46 INFO - PROCESS | 1650 | RSS: Main: 268771328 05:24:46 INFO - PROCESS | 1650 | 05:27:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:27:50 INFO - PROCESS | 1650 | RSS: Main: 274108416 05:27:50 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:30:52 INFO - PROCESS | 1650 | RSS: Main: 276549632 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | __start_tp_report 05:30:52 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 05:30:52 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 05:30:52 INFO - PROCESS | 1650 | |i|pagename|runs| 05:30:52 INFO - PROCESS | 1650 | |0;dojo.html;2840.9857950710243;2889.5521194214903;3273.9801845914503;3304.917377065573;3331.550240717502;5592.496675299222;5601.747921343539;5602.943970560298;5611.298587676541;5744.224529688491;5043.218301163325;5519.227308176858;5646.463585959327;5658.151277308406;5680.0627896397045;5121.718305493197;5147.536721695046;5160.845174644766;5247.632665713401;5262.552683021929;5202.843914682566;5238.3975842778;5245.186996015624;5261.854035574504;5263.842084737464;4227.682923780729;4403.691741578091;5068.391792984866;5096.541311282002;5240;5091.032703786269;5104.927965127327;5159;5224.216367544861;5267.841964741064;5131.255967884673;5143.151380022292;5159.097157997373;5178.818741344035;5196.8960620787375;5530.6128571000045;5534.75093620788;5715.885682286373;5723.742431590588;5732.971335143297;5198.2722418861385;5861.4724674779045;6085.513158947318;6098.57309988301;6130.826205142282;6204.937950620481;6207.099970504297;6212;6224.937750622526;6247.9062814057825;5693.857653558694;5697.800576979786;5698.34469036062;5708.429157084258;5726.284214473191;6163.19878415803;6175.814725558241;6196.721147548371;6214.067889816563;6236.095766113888;6004.7089875676065;6059.7273122709585;6063.636181829104;6068.605540639845;6091.878162436727;6039.7282122304605;6206.627602343783;6212.1303017577575;6223.688815559204;6239.906401403982;6072.36240194782;6096.889554637438;6103.298120716086;6128.693565321716;6184.6598437085495;6129.2032035835055;6176.629402235881;6181.134641150243;6192.81421557354;6201.286852012076;4825.613950883921;4879.731614761151;4885.267209918541;4885.853424397274;4894.706317620954;4328.48058233014;4370.650347972218;4373.540778218305;4381.9342709859375;4443.02253504235;5691.402402747734;5737.655740655574;5747.08046712524;5757.309122905278;5763.135529670583;5890.293164820249;6228.096925945712;6308.653024083627;6319.715612797435;6325.114483972247;4761.619070474354;5335.253064570963;5553.250311208016;5641.830745077655;5773.913391299134;4178.811953462102;5231.843044708665;5347.385050719139;5381.75782089799;5392.164214546751;6269.247690277196;6290.779822706183;6303;6303.716332765037;6314.431701146918;5121.231815227746;6036.517078633787;6146.846328841814;6251.374862513712;6257.5932564383165;6228.314885362516;6265.373462653698;6289.559730818799;6301.243850737941;6326.620402775664;6086.482648974887;6126.663033533198;6169.598976066542;6171.84570385726;6212.161358216583;6095.7708482108;6110.052941794029;6258.968705156627;6278.183836101278;6290.968545157245;5981.641101533748;6008.969955150196;6037.426444487766;6099.067674246629;6126.517673797367;5878.823635290948;6034.547408944434;6065;6070.757169713339;6095.207623008981;4677.929831052469;5786.479216870502;5791.913121303268;5835.824925252249;5948.672822994604;6028.216331876828;6064.151018857452;6077.20996270482;6083.482903953213;6089.451949324582;5840.386759390372;5851.590388672752;5869.53043756507;5886.646801191942;5900.527957763283 05:30:52 INFO - PROCESS | 1650 | |1;ext.html;470.3250835051701;492.1682356816978;516.9870753231171;529.105811179107;538.7737150396833;39481.80259098704;40896.795516022416;41053;41086.383704244385;41183;43266.134677306516;43700;43790.34314485277;43816.685499435065;43821.34267985983;9513.904860951407;9546.61813527458;9662.227021838271;9673.661421850236;9689.515524223796;9282.329180747463;9432.905670943272;9495.43027418357;9565.473898935556;9591.280653950947;9502.714918552454;9549.713508594754;9554.187894029015;9558.474283914382;9561.760955976122;9447.338686291961;9520.381175223589;9547.713568592953;9578.281628877861;9583.089606487372;9219.907800921972;9274.53627318638;9333.533323333806;9355.625774968994;9362.344635875492;41234.793826030975;41402.79298603518;41410;41432.79283603562;41584.37623435651;3347;3351.6815902489225;3391.593008838955;3467.861285548575;3864;30311.393772124433;30742;30802.53796193059;30887.691123088705;31037.534436983464;3203.85582648779;3230.4185246655593;3233.369492948876;3257.8696852125886;3328.0348698877297;290606;357385;358538.9999999974;358972;372464;58711.41288587145;58879;59158.40841591615;59178;59482.40517594855;61750.0737488938;62819.057714134324;62831.685841571416;63027.73944521084;63128.68435657792;12758.043146763845;14336.21150836714;15043.623909402133;16123.83876161247;16349.264283107288;11936.224145430693;12209.877901220874;12618.92739117167;12700.983921286275;12703.428345724464;14998.100113993196;15400.15299158557;15818.841811581737;15946.681066378607;15985.920070399807;6485.416312531895;6561.80314590563;7027.227005029443;7109.862422012557;7115.399035217035;775.8370742144212;833.083608031173;964.1997142371814;970.96601618943;1036.9688909332733;28928;29907;30023.998486402637;30565;32000.519992200134;1008.4403156248206;1053.1627356251797;1098.1763677242068;1108.578740078765;1127.3236058364917;14930.70138597222;15458.922705386402;15645.174980901806;15872;15912.363505459884;626504;633468;634177;637632;637766;58791.41208587945;58814.41185588175;58969;58985.11522327083;59299;1348.858369871169;1366.180291824897;1379.2483096712315;1383.9169649820842;1389.020740378031;24665.506689865742;28427.857860710563;29704.851475742482;29846.850765746032;30344.393112137193;49600.50399495959;50039;50693;50701.23948140855;50747;550886;563751;566739;569323;570026.2480313911;39712.60287397089;40267.798661006505;40544.18911621692;41275.38086928638;41513.37729934114;46442.6067217984;47164;47696;48275.75862120667;48297.5170248293;39351.80324098361;39448.802755986035;39544.40683389694;40556;41284.38073428841;30248.395032099674;38423.04442811369;38592.80703596464;38607;39958;37741.24517509579;38438;38544;38680.806595967966;38733.80633096817;33061.33877322392;34061.97814065582;34736.131596710286;34801.82599086988;34886.12784680403;25015.249542513753;25094.49811003806;25714;25868.22395328143;25964.350891227114 05:30:52 INFO - PROCESS | 1650 | |2;jquery.html;321.8326470235477;327.57251786418715;371.9386301260292;376.7475791219882;388.9786061766603;16077.919610401961;19273;19956.60086798265;20431.97840107996;20633.27783527579;19330.90334548329;20288.985550722442;20313.984300784938;20525.589488210226;20676.68984965223;4739.597134243593;5222.712750798704;6037.939620603804;6043.063325184581;6067.696615169246;4149.439825623533;4382.956170438287;5834.183214349994;5893.499052580536;6244.656543890084;6534.281229064799;6620.834479138036;6623.9006414903815;6625.701843417034;6630.6021638701595;6340.207474065742;6563.113979612739;6594.571352862049;6596.86806263872;6630.801075967729;5392.757325920304;5660.405657405954;5680.289963754531;5682.545396368322;5709.143628455723;25401.110961116457;25420.49159016828;25454.109106181193;25551.488970220493;25612.743872561223;2029.2998915374171;2186.9343919682433;2332.137876070941;2373.335466069503;2798.965707937048;14945.775813362809;15412;15535;15535.378584856593;16041.91979040109;1849.5838436351848;1867.4771064102074;2023.4132101690493;2051.9692204616945;2127.617028934791;110011.44994275058;116205.99999999916;130018;137219.31390343086;138545.6145438543;20410.69383959242;20442.28452004173;21114.577708446053;21192.576148477252;21344.916698740784;34558.82720586381;34825.12937176591;34989.30021399558;35026.64973350285;35140.29719405597;10545.57817687284;10642.25504214713;10866.293690910066;10953.561857525769;11048.116150707923;8729.388942773945;8877.467351958903;8878.600462979182;9039.728808135635;9068.954655226682;7961.681532738626;8159.184081591793;9679.516024198761;9897.80204395908;9938.850917236246;1584.9841501585067;1645.1691895592649;1671.4150047483283;3790.583035866108;3916.4321173429694;798.616664001271;852.5737131434283;911.2026976395654;941.1929096135436;972.5429048347265;19267.710984335245;21030.78969210319;21232;21359.551960174238;21468.570628587342;878.2227728460358;919.1176470588176;927.5501381829807;954.9379290346105;983.4590974963742;10849.457527123612;11206.38364889939;11263.380514071805;11292.491837867316;11324.094072474183;585431;585523.0723846353;590727;596433;597155;132457.67542324454;132677;134558;135316.32341838424;137640;946.5645802930729;958.5351104714208;983.5033308179467;987.6247026130025;991.4596544883057;15590;18597.535061623566;18646;18722.940476547297;18794;55018;55573.44426555844;56167.15749263683;56366.87266254734;56719.14921276267;75770.24229757632;77738.6113069431;80108.99727506626;82232.58883705543;83897;6212.502999759918;6334.303226645157;7534.246575342422;8210;8244.051934027664;10332.793344133226;12416.81374779397;12880.935595322338;13297.867021329663;13387.732245354844;10451.964728108323;11226.045786108272;11458.541658333574;11589.014933730727;11669.35818529989;9772.853407198756;10017.198624109908;10447;11044.944775276394;11359.48882300282;11490.482928268415;11559.306441613193;11787.057035436974;11840;11845.881541184477;7330.79041958071;8117.715879944174;8235.95882020586;8296.751097467086;8304.87542686848;8880.467171969702;11295.322280662858;11710.180287379802;11764.294142351486;11787.764244715228 05:30:52 INFO - PROCESS | 1650 | |3;mootools.html;1502.8722558582517;1509.2227502836042;1765.5586103474132;1880.6050729346837;1912.2446633579727;7944.721934732285;8231.094579596254;8447.15528447155;8991.426500362435;9034.457932524045;8433.072362040171;8737.301015918727;8761.342899282547;8822.073682263366;8841.5579221039;2308.74603793583;2349.6827928229727;2353.9058437662475;2356.3284463927785;2373.2998765364173;2342.613468777658;2360.905563777447;2385.320183747632;2388.259639511757;2394.6048901931163;2300.1949317738827;2343.1564636730764;2367.112332875172;2376.394019525019;2386.1052105460453;2333.218371845434;2335.0076217607534;2354.387859156623;2358.351453350334;2365.7161140663056;2231.62062449384;2244.55108978205;2247.539254452833;2252.1441852096264;2255.165588732163;8798.648054077828;8809.691660791841;8861.822763544758;8863.980642226097;8909.109089091038;4551.203539380595;4715.8210447388155;5198.960207958424;5361.892762144775;5366.007288651573;3478.286951175003;3509.4560343146854;3623.3296840084668;3634.6547078027547;3644.8131121327256;1031.4378663628265;1066.0884943373383;1069.3209811769582;1072.975308580304;1089.096050278267;10474.371537707762;11460.140489463323;11537.307761534252;11549.884501154966;11614.4192790361;4128.896777580585;4543.114092751948;4633.096546173531;4810.8797280068275;4811.879703007453;9804.852927206097;9870.309078364446;9871.407715536947;9878.654247101316;9914.107730304308;5350.785968561214;5381.219723140122;5384.515393614593;5391.137418013178;5420.024395608828;4415.712978656377;4595.931061034087;4718.787654555554;4839.951600484021;4858.878528036827;3846.6922646188236;3878.8060596970604;3969.6030396960073;3971.252648834561;4031.1937612477623;1290.7547565962436;1294.2816736711027;1295.6307452376027;1298.448159532195;1307.0066749270434;906.7551761024619;923.70903165504;929.2148134826061;937.4609599480326;952.1620973543376;5312.548433383128;5897.882042359214;5918.62514541927;6048.123022161762;6090.63456192621;430.18265295937346;432.11847830426206;435.86052463212104;436.67031391299366;436.9300911854088;3419.9145021374666;3738.6822120120096;3757.666028559822;4011.659008984269;4038.939415908705;10331.22515811302;10547.630832920811;10665.680029599125;10674.092702120406;10681.359118452918;9580.760480987892;10603.681889543326;10604.787904241872;10635.255532112678;10648.615679961555;7951.483153594998;8249.917500825035;8255.876161857577;8280.378971577156;8287.088420273885;5817.970910145422;5851.853703657443;5898.380670029585;6019.819405417845;6180.381961803783;8759.868601971102;9988.750281242736;9996.500174991366;10098.747531311776;10131.64539241123;8244.422890397615;9524.333296669167;9843.950780246052;10062.69811905644;10180.697215696817;6484;6821.727130914708;7056.894146587908;7199.460040496879;7333.303336183054;8136.267735903797;8274.544900030556;8346.207110324509;8347.499150051017;8356.582170891552;7309.122905251404;7537.849243015218;7538.509996850187;7595.050618672666;7596.08846938371;7329.853402931805;7377.631118444164;7446.994655721408;7508.2867127622785;7528.623568821647;6838.8290292741085;6877.690503927235;6996.790096297119;7040.331168538981;7074;4909.901801963869;4992.950070499249;5060.797568097235;5061.468545802638;5081.9745901271735;5039.2944987702485;5058.873528161826;5181.1969144782615;5202.1416466282635;5240.135377662643 05:30:52 INFO - PROCESS | 1650 | |4;prototype.html;265.952128616849;302.6564848896502;309.8667572943634;313.5297054418372;316.80358177929685;4992.651983964337;5360.865978350544;5371.355437347524;5567.582431317652;5709.77160913563;4258.552851950547;5220.92168617471;5482.656749096467;5487.259220005298;5498.780048798043;3917.3928041153667;4186.964450773188;4202.810873510699;4208.327711902948;4213.565943755844;3847.095687634611;4133.462649855514;4166.958330416703;4191.308434108369;4226.957730422702;4119.876403707894;4125.773082480462;4160.188763191179;4168.5623009584015;4198.5591512891015;3133.780635355526;4113.4241206231;4116.91766164675;4131.834726610932;4177.686673499469;3577.2130131371055;3591.3356029134693;3594.964050359489;3620.9275814483826;3645.5989841117457;5252.737363131828;5365.32475254448;5410.594205434608;5425.6202065855405;5449.373322067973;3957.663598594123;4739.857804265877;4748.596369308613;4751.121042607094;4755.619550435957;4871.391076115486;5059.544640982293;5808.651480911159;5823.621464604787;5828.504577110951;2987.6414830220515;3291.588551431071;3334.9666503334897;3480.868717666743;3645.2345007548447;5704.857378565527;6131.601445906002;6160.661163636043;6166.238594457377;6184.412480814315;3949.1311911379453;3958.980205099014;3985.3025720499377;3986.2824691555797;3998.9400158997637;3714.294284086015;3996.800159991989;4036.798160091984;4044.4742183515955;4051.756894586334;4730.526947305242;4734.242521196592;4738.4550776660435;4742.79460051071;4756.334113224151;4959.578435832923;5655.519280861173;5848.736806843703;5875.265591801025;5877.412258774174;1286.7812471879804;1317.7298653775954;1337.1909994453215;1343.4693296147966;1365.6381059019368;890.3589415620743;899.1053901368181;916.225789208118;920.6236676169036;924.3638759395966;4435.756033418128;5182.6372153949615;5450.863728406822;5453.509184173444;5478.260434841245;5122.078025955364;5476.17857321405;5957.017092179828;6010.338862725097;6011.368806275365;4470.687051906335;4795.712257264575;5099.566536844335;5251.737413129328;5281.973590132025;2639.5512762830367;2656.4288677934337;2658.218993274511;2663.7735792457856;2685.436058427752;4303.783551470995;4329.891752706145;5189.610779191576;5199.402068762065;5226.346706661668;4540.091981603692;4886.804527818847;4941.888075183055;5096.986690257328;5177.508136727005;4550.203714349976;4936.432310284364;4976.601871850244;5223.3883058470765;5227.790888364423;4809.426956578214;4840.685355451885;5071.366079240095;5089.440161582297;5117.897642047213;4123.422720819028;4577.542245775396;4663.790129444251;4964.833264182987;5030.748462576783;4347.369631403492;4482.551744825491;5161.535461808456;5199.870003249949;5256.342957130359;3270.936945492677;3452.913677158091;3482.2316258573987;3490.4118626024588;3493.0568746438826;4390.499737768812;4485.863686079486;4752.025834703808;4789.257665061921;5119.950410165832;3915.4518367428013;4226.949929284233;4902.583280421061;4911.607071434348;4921.926171107508;4409.382686423967;4550.863474095782;4884.389451318585;4894.314795928502;4927.679700819435;4353.434053573015;4395.9340609891515;4445.799939002817;4796.472388037383;4822.903541929071;4301.214995776718;4463.852983646586;4525.524819893863;4554.337666751678;4955.456848353445;4138.91722165561;4397.504848351576;4496.685232033857;4775.785089670903;4790.401199850019 05:30:52 INFO - PROCESS | 1650 | |5;yui.html;679.8300424893777;710.594960872303;714.9356557909787;743.5464366736293;794.8847417124515;12643.873561264385;14087.29563521825;15303.540893773177;15972.201389930515;16191.757123643125;12398.504059837596;14602.853971460283;14721.190334531597;14725.926370368159;14755.40978360867;708.1785129250071;723.7286017743346;865.3726048614735;883.4213590098464;890.1410139215656;741.4735537768191;845.5138295480124;870.5342641686699;887.6050157679803;907.8774365460678;954.4464210757778;955.2310390135938;955.2358113509199;955.2596737528436;955.7037318431273;928.5078908178676;946.0633972367341;950.9381890177116;951.619352259095;953.3755390219452;695.2352412346379;703.9859202815966;711.6370650967992;711.7686751805684;714.1430283659578;14565.70868582633;14606.780898286428;14819.259037048105;14831.184284864328;14898.329575169144;14264.643383915381;14415.279236038261;14425.78361324581;14554.272286385638;14556.636084097981;11557.364344961024;11626.360550169737;11638.359890206035;11717.882821171765;11814.232074915102;3672.320620685155;3805.4482100095606;3817.675497582709;3823.50294461721;3940.7635541867294;87446;91385.54307228488;91683;104581.99999999924;116622;10942.726431839268;11066.170037247077;11104.389258590694;11150.776984460266;11178.27341222818;21955;22064.88967555152;22137.66793498099;22145.224917127824;22198;7718.961405192938;7864.213578642204;7910.802229944183;8010.639521221559;8153.673853045931;4218.114196018809;4308.159908817751;4324.827006919751;4333.826646934087;4364.8254069837485;3571.4107172316276;3708.35103856824;3709.9443508347395;3712.6101759315425;3859.864904728321;639.389383139101;709.6806437103324;712.2023333866;720.7693538067768;726.2410780734145;589.9115132730125;650.2067477677226;674.3795707948699;681.2948598200838;682.6893763337769;8933.677521842628;9123.178913897782;9252.444853308823;9457.621695132118;9673.467959262309;515.4561937156336;599.8320470268332;630.6941133550224;641.5060403489288;658.7397977798743;3986.461827653288;4103.651189648914;4163.83344666216;4168.374743788456;4310.762908040025;87545.56227218822;98852;98860.50569747249;144180.27909860385;161225;106016;126061;127047.36476317744;127491;127686;69033.65483172552;73401;81815.9999999988;97737;107798.46100769447;7226.4218862491925;9780.85328720084;9833.4099954003;9855.655052073143;10058.748531286483;59971.700141499015;64184.03723944051;66513.33716657122;67241;68552.31447685459;97812;100286.49856750961;101892;102604;103081.23809476278;12874.291913944822;13456.86543134556;13476.595702128952;13867.79198312006;14453.566393008226;14531.34608942579;14647.853521465075;14738.484153054591;14756.409743610135;14908.403663853325;11185.552577896793;13356.76976700046;13402.32988350559;13437.731245375233;13649.863501365257;12270.693232669255;13012.089153759525;13609.863901360859;13691.041627086008;14061.789073163705;13420.261885596386;13423.932880335926;13577;13854.722905541632;13948.511802087283;10061.798764024825;10995.670129896116;11157.609483668033;11375.829362559403;11487.483063262007;11346.149038821955;11476.827847582459;11507.654770356903;11608.6517404478;11711 05:30:52 INFO - PROCESS | 1650 | __end_tp_report 05:30:52 INFO - PROCESS | 1650 | __start_cc_report 05:30:52 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,359 05:30:52 INFO - PROCESS | 1650 | __end_cc_report 05:30:52 INFO - PROCESS | 1650 | __startTimestamp1476793852967__endTimestamp 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | ------- Summary: start ------- 05:30:52 INFO - PROCESS | 1650 | Number of tests: 6 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | [#0] dojo.html Cycles:165 Average:5644.58 Median:5851.59 stddev:691.59 (11.8%) stddev-sans-first:657.81 05:30:52 INFO - PROCESS | 1650 | Values: 2841.0 2889.6 3274.0 3304.9 3331.6 5592.5 5601.7 5602.9 5611.3 5744.2 5043.2 5519.2 5646.5 5658.2 5680.1 5121.7 5147.5 5160.8 5247.6 5262.6 5202.8 5238.4 5245.2 5261.9 5263.8 4227.7 4403.7 5068.4 5096.5 5240.0 5091.0 5104.9 5159.0 5224.2 5267.8 5131.3 5143.2 5159.1 5178.8 5196.9 5530.6 5534.8 5715.9 5723.7 5733.0 5198.3 5861.5 6085.5 6098.6 6130.8 6204.9 6207.1 6212.0 6224.9 6247.9 5693.9 5697.8 5698.3 5708.4 5726.3 6163.2 6175.8 6196.7 6214.1 6236.1 6004.7 6059.7 6063.6 6068.6 6091.9 6039.7 6206.6 6212.1 6223.7 6239.9 6072.4 6096.9 6103.3 6128.7 6184.7 6129.2 6176.6 6181.1 6192.8 6201.3 4825.6 4879.7 4885.3 4885.9 4894.7 4328.5 4370.7 4373.5 4381.9 4443.0 5691.4 5737.7 5747.1 5757.3 5763.1 5890.3 6228.1 6308.7 6319.7 6325.1 4761.6 5335.3 5553.3 5641.8 5773.9 4178.8 5231.8 5347.4 5381.8 5392.2 6269.2 6290.8 6303.0 6303.7 6314.4 5121.2 6036.5 6146.8 6251.4 6257.6 6228.3 6265.4 6289.6 6301.2 6326.6 6086.5 6126.7 6169.6 6171.8 6212.2 6095.8 6110.1 6259.0 6278.2 6291.0 5981.6 6009.0 6037.4 6099.1 6126.5 5878.8 6034.5 6065.0 6070.8 6095.2 4677.9 5786.5 5791.9 5835.8 5948.7 6028.2 6064.2 6077.2 6083.5 6089.5 5840.4 5851.6 5869.5 5886.6 5900.5 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | [#1] ext.html Cycles:180 Average:66095.91 Median:41069.69 stddev:141576.96 (344.7%) stddev-sans-first:141888.38 05:30:52 INFO - PROCESS | 1650 | Values: 470.3 492.2 517.0 529.1 538.8 39481.8 40896.8 41053.0 41086.4 41183.0 43266.1 43700.0 43790.3 43816.7 43821.3 9513.9 9546.6 9662.2 9673.7 9689.5 9282.3 9432.9 9495.4 9565.5 9591.3 9502.7 9549.7 9554.2 9558.5 9561.8 9447.3 9520.4 9547.7 9578.3 9583.1 9219.9 9274.5 9333.5 9355.6 9362.3 41234.8 41402.8 41410.0 41432.8 41584.4 3347.0 3351.7 3391.6 3467.9 3864.0 30311.4 30742.0 30802.5 30887.7 31037.5 3203.9 3230.4 3233.4 3257.9 3328.0 290606.0 357385.0 358539.0 358972.0 372464.0 58711.4 58879.0 59158.4 59178.0 59482.4 61750.1 62819.1 62831.7 63027.7 63128.7 12758.0 14336.2 15043.6 16123.8 16349.3 11936.2 12209.9 12618.9 12701.0 12703.4 14998.1 15400.2 15818.8 15946.7 15985.9 6485.4 6561.8 7027.2 7109.9 7115.4 775.8 833.1 964.2 971.0 1037.0 28928.0 29907.0 30024.0 30565.0 32000.5 1008.4 1053.2 1098.2 1108.6 1127.3 14930.7 15458.9 15645.2 15872.0 15912.4 626504.0 633468.0 634177.0 637632.0 637766.0 58791.4 58814.4 58969.0 58985.1 59299.0 1348.9 1366.2 1379.2 1383.9 1389.0 24665.5 28427.9 29704.9 29846.9 30344.4 49600.5 50039.0 50693.0 50701.2 50747.0 550886.0 563751.0 566739.0 569323.0 570026.2 39712.6 40267.8 40544.2 41275.4 41513.4 46442.6 47164.0 47696.0 48275.8 48297.5 39351.8 39448.8 39544.4 40556.0 41284.4 30248.4 38423.0 38592.8 38607.0 39958.0 37741.2 38438.0 38544.0 38680.8 38733.8 33061.3 34062.0 34736.1 34801.8 34886.1 25015.2 25094.5 25714.0 25868.2 25964.4 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | [#2] jquery.html Cycles:180 Average:36536.59 Median:1560.40 stddev:98951.06 (6341.4%) stddev-sans-first:99191.28 05:30:52 INFO - PROCESS | 1650 | Values: 321.8 327.6 371.9 376.7 389.0 16077.9 19273.0 19956.6 20432.0 20633.3 19330.9 20289.0 20314.0 20525.6 20676.7 4739.6 5222.7 6037.9 6043.1 6067.7 4149.4 4383.0 5834.2 5893.5 6244.7 6534.3 6620.8 6623.9 6625.7 6630.6 6340.2 6563.1 6594.6 6596.9 6630.8 5392.8 5660.4 5680.3 5682.5 5709.1 25401.1 25420.5 25454.1 25551.5 25612.7 2029.3 2186.9 2332.1 2373.3 2799.0 14945.8 15412.0 15535.0 15535.4 16041.9 1849.6 1867.5 2023.4 2052.0 2127.6 110011.4 116206.0 130018.0 137219.3 138545.6 20410.7 20442.3 21114.6 21192.6 21344.9 34558.8 34825.1 34989.3 35026.6 35140.3 10545.6 10642.3 10866.3 10953.6 11048.1 8729.4 8877.5 8878.6 9039.7 9069.0 7961.7 8159.2 9679.5 9897.8 9938.9 1585.0 1645.2 1671.4 3790.6 3916.4 798.6 852.6 911.2 941.2 972.5 19267.7 21030.8 21232.0 21359.6 21468.6 878.2 919.1 927.6 954.9 983.5 10849.5 11206.4 11263.4 11292.5 11324.1 585431.0 585523.1 590727.0 596433.0 597155.0 132457.7 132677.0 134558.0 135316.3 137640.0 946.6 958.5 983.5 987.6 991.5 15590.0 18597.5 18646.0 18722.9 18794.0 55018.0 55573.4 56167.2 56366.9 56719.1 75770.2 77738.6 80109.0 82232.6 83897.0 6212.5 6334.3 7534.2 8210.0 8244.1 10332.8 12416.8 12880.9 13297.9 13387.7 10452.0 11226.0 11458.5 11589.0 11669.4 9772.9 10017.2 10447.0 11044.9 11359.5 11490.5 11559.3 11787.1 11840.0 11845.9 7330.8 8117.7 8236.0 8296.8 8304.9 8880.5 11295.3 11710.2 11764.3 11787.8 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | [#3] mootools.html Cycles:180 Average:5642.86 Median:4951.43 stddev:3157.69 (63.8%) stddev-sans-first:3151.22 05:30:52 INFO - PROCESS | 1650 | Values: 1502.9 1509.2 1765.6 1880.6 1912.2 7944.7 8231.1 8447.2 8991.4 9034.5 8433.1 8737.3 8761.3 8822.1 8841.6 2308.7 2349.7 2353.9 2356.3 2373.3 2342.6 2360.9 2385.3 2388.3 2394.6 2300.2 2343.2 2367.1 2376.4 2386.1 2333.2 2335.0 2354.4 2358.4 2365.7 2231.6 2244.6 2247.5 2252.1 2255.2 8798.6 8809.7 8861.8 8864.0 8909.1 4551.2 4715.8 5199.0 5361.9 5366.0 3478.3 3509.5 3623.3 3634.7 3644.8 1031.4 1066.1 1069.3 1073.0 1089.1 10474.4 11460.1 11537.3 11549.9 11614.4 4128.9 4543.1 4633.1 4810.9 4811.9 9804.9 9870.3 9871.4 9878.7 9914.1 5350.8 5381.2 5384.5 5391.1 5420.0 4415.7 4595.9 4718.8 4840.0 4858.9 3846.7 3878.8 3969.6 3971.3 4031.2 1290.8 1294.3 1295.6 1298.4 1307.0 906.8 923.7 929.2 937.5 952.2 5312.5 5897.9 5918.6 6048.1 6090.6 430.2 432.1 435.9 436.7 436.9 3419.9 3738.7 3757.7 4011.7 4038.9 10331.2 10547.6 10665.7 10674.1 10681.4 9580.8 10603.7 10604.8 10635.3 10648.6 7951.5 8249.9 8255.9 8280.4 8287.1 5818.0 5851.9 5898.4 6019.8 6180.4 8759.9 9988.8 9996.5 10098.7 10131.6 8244.4 9524.3 9844.0 10062.7 10180.7 6484.0 6821.7 7056.9 7199.5 7333.3 8136.3 8274.5 8346.2 8347.5 8356.6 7309.1 7537.8 7538.5 7595.1 7596.1 7329.9 7377.6 7447.0 7508.3 7528.6 6838.8 6877.7 6996.8 7040.3 7074.0 4909.9 4993.0 5060.8 5061.5 5082.0 5039.3 5058.9 5181.2 5202.1 5240.1 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | [#4] prototype.html Cycles:180 Average:4312.34 Median:4736.35 stddev:1291.47 (27.3%) stddev-sans-first:1258.88 05:30:52 INFO - PROCESS | 1650 | Values: 266.0 302.7 309.9 313.5 316.8 4992.7 5360.9 5371.4 5567.6 5709.8 4258.6 5220.9 5482.7 5487.3 5498.8 3917.4 4187.0 4202.8 4208.3 4213.6 3847.1 4133.5 4167.0 4191.3 4227.0 4119.9 4125.8 4160.2 4168.6 4198.6 3133.8 4113.4 4116.9 4131.8 4177.7 3577.2 3591.3 3595.0 3620.9 3645.6 5252.7 5365.3 5410.6 5425.6 5449.4 3957.7 4739.9 4748.6 4751.1 4755.6 4871.4 5059.5 5808.7 5823.6 5828.5 2987.6 3291.6 3335.0 3480.9 3645.2 5704.9 6131.6 6160.7 6166.2 6184.4 3949.1 3959.0 3985.3 3986.3 3998.9 3714.3 3996.8 4036.8 4044.5 4051.8 4730.5 4734.2 4738.5 4742.8 4756.3 4959.6 5655.5 5848.7 5875.3 5877.4 1286.8 1317.7 1337.2 1343.5 1365.6 890.4 899.1 916.2 920.6 924.4 4435.8 5182.6 5450.9 5453.5 5478.3 5122.1 5476.2 5957.0 6010.3 6011.4 4470.7 4795.7 5099.6 5251.7 5282.0 2639.6 2656.4 2658.2 2663.8 2685.4 4303.8 4329.9 5189.6 5199.4 5226.3 4540.1 4886.8 4941.9 5097.0 5177.5 4550.2 4936.4 4976.6 5223.4 5227.8 4809.4 4840.7 5071.4 5089.4 5117.9 4123.4 4577.5 4663.8 4964.8 5030.7 4347.4 4482.6 5161.5 5199.9 5256.3 3270.9 3452.9 3482.2 3490.4 3493.1 4390.5 4485.9 4752.0 4789.3 5120.0 3915.5 4226.9 4902.6 4911.6 4921.9 4409.4 4550.9 4884.4 4894.3 4927.7 4353.4 4395.9 4445.8 4796.5 4822.9 4301.2 4463.9 4525.5 4554.3 4955.5 4138.9 4397.5 4496.7 4775.8 4790.4 05:30:52 INFO - PROCESS | 1650 | 05:30:52 INFO - PROCESS | 1650 | [#5] yui.html Cycles:180 Average:23201.06 Median:3811.56 stddev:35681.86 (936.1%) stddev-sans-first:35741.89 05:30:52 INFO - PROCESS | 1650 | Values: 679.8 710.6 714.9 743.5 794.9 12643.9 14087.3 15303.5 15972.2 16191.8 12398.5 14602.9 14721.2 14725.9 14755.4 708.2 723.7 865.4 883.4 890.1 741.5 845.5 870.5 887.6 907.9 954.4 955.2 955.2 955.3 955.7 928.5 946.1 950.9 951.6 953.4 695.2 704.0 711.6 711.8 714.1 14565.7 14606.8 14819.3 14831.2 14898.3 14264.6 14415.3 14425.8 14554.3 14556.6 11557.4 11626.4 11638.4 11717.9 11814.2 3672.3 3805.4 3817.7 3823.5 3940.8 87446.0 91385.5 91683.0 104582.0 116622.0 10942.7 11066.2 11104.4 11150.8 11178.3 21955.0 22064.9 22137.7 22145.2 22198.0 7719.0 7864.2 7910.8 8010.6 8153.7 4218.1 4308.2 4324.8 4333.8 4364.8 3571.4 3708.4 3709.9 3712.6 3859.9 639.4 709.7 712.2 720.8 726.2 589.9 650.2 674.4 681.3 682.7 8933.7 9123.2 9252.4 9457.6 9673.5 515.5 599.8 630.7 641.5 658.7 3986.5 4103.7 4163.8 4168.4 4310.8 87545.6 98852.0 98860.5 144180.3 161225.0 106016.0 126061.0 127047.4 127491.0 127686.0 69033.7 73401.0 81816.0 97737.0 107798.5 7226.4 9780.9 9833.4 9855.7 10058.7 59971.7 64184.0 66513.3 67241.0 68552.3 97812.0 100286.5 101892.0 102604.0 103081.2 12874.3 13456.9 13476.6 13867.8 14453.6 14531.3 14647.9 14738.5 14756.4 14908.4 11185.6 13356.8 13402.3 13437.7 13649.9 12270.7 13012.1 13609.9 13691.0 14061.8 13420.3 13423.9 13577.0 13854.7 13948.5 10061.8 10995.7 11157.6 11375.8 11487.5 11346.1 11476.8 11507.7 11608.7 11711.0 05:30:52 INFO - PROCESS | 1650 | -------- Summary: end -------- 05:30:52 INFO - PROCESS | 1650 | 05:30:53 INFO - TEST-INFO | 1650: exit 0 05:30:53 INFO - TEST-OK | dromaeo_css | took 1115580ms 05:30:53 INFO - TEST-START | kraken 05:30:53 INFO - operating with platform_type : mac_ 05:30:53 INFO - Initialising browser for kraken test... 05:30:53 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpZLOgGM/profile http://localhost:49266/getInfo.html) 05:30:56 INFO - PROCESS | 1659 | 2016-10-18 05:30:56.368 plugin-container[1660:9853] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8843, name = 'com.apple.tsm.portname' 05:30:56 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:56 INFO - PROCESS | 1659 | 2016-10-18 05:30:56.371 plugin-container[1660:9853] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x442b, name = 'com.apple.CFPasteboardClient' 05:30:56 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:56 INFO - PROCESS | 1659 | 2016-10-18 05:30:56.371 plugin-container[1660:9853] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:30:56 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 05:30:56 INFO - PROCESS | 1659 | colorDepth:24 05:30:56 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 05:30:56 INFO - PROCESS | 1659 | __metrics 05:30:56 ERROR - PROCESS | 1659 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 05:30:57 INFO - TEST-INFO | 1659: exit 0 05:30:57 INFO - Browser initialized. 05:30:57 INFO - Running cycle 1/1 for kraken test... 05:30:57 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/T/tmpZLOgGM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:30:59 INFO - PROCESS | 1661 | 2016-10-18 05:30:59.653 plugin-container[1662:9971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 05:30:59 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:59 INFO - PROCESS | 1661 | 2016-10-18 05:30:59.654 plugin-container[1662:9971] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 05:30:59 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:30:59 INFO - PROCESS | 1661 | 2016-10-18 05:30:59.654 plugin-container[1662:9971] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:31:09 INFO - PROCESS | 1661 | RSS: Main: 237305856 05:31:09 INFO - PROCESS | 1661 | 05:31:11 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:31:12 INFO - PROCESS | 1661 | RSS: Main: 249573376 05:31:12 INFO - PROCESS | 1661 | 05:31:15 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:31:15 INFO - PROCESS | 1661 | RSS: Main: 257081344 05:31:15 INFO - PROCESS | 1661 | 05:31:19 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:31:19 INFO - PROCESS | 1661 | RSS: Main: 259911680 05:31:19 INFO - PROCESS | 1661 | 05:31:21 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:31:22 INFO - PROCESS | 1661 | RSS: Main: 262275072 05:31:22 INFO - PROCESS | 1661 | 05:31:24 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:31:24 INFO - PROCESS | 1661 | RSS: Main: 262672384 05:31:24 INFO - PROCESS | 1661 | 05:31:27 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:31:28 INFO - PROCESS | 1661 | RSS: Main: 263987200 05:31:28 INFO - PROCESS | 1661 | 05:31:31 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:31:32 INFO - PROCESS | 1661 | RSS: Main: 263852032 05:31:32 INFO - PROCESS | 1661 | 05:31:34 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:31:35 INFO - PROCESS | 1661 | RSS: Main: 264376320 05:31:35 INFO - PROCESS | 1661 | 05:31:37 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:31:37 INFO - PROCESS | 1661 | RSS: Main: 263639040 05:31:37 INFO - PROCESS | 1661 | 05:31:39 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:31:40 INFO - PROCESS | 1661 | RSS: Main: 263471104 05:31:40 INFO - PROCESS | 1661 | 05:31:42 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:31:43 INFO - PROCESS | 1661 | RSS: Main: 263467008 05:31:43 INFO - PROCESS | 1661 | 05:31:46 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:31:46 INFO - PROCESS | 1661 | RSS: Main: 263528448 05:31:46 INFO - PROCESS | 1661 | 05:31:49 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:31:50 INFO - PROCESS | 1661 | RSS: Main: 263860224 05:31:50 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:31:52 INFO - PROCESS | 1661 | RSS: Main: 265568256 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | __start_tp_report 05:31:52 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 05:31:52 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 05:31:52 INFO - PROCESS | 1661 | |i|pagename|runs| 05:31:52 INFO - PROCESS | 1661 | |0;ai-astar;107;120;119;118;116;118;117;118;121;117 05:31:52 INFO - PROCESS | 1661 | |1;audio-beat-detection;143;116;114;148;115;116;115;114;163;103 05:31:52 INFO - PROCESS | 1661 | |2;audio-dft;197;194;181;226;198;198;206;201;171;273 05:31:52 INFO - PROCESS | 1661 | |3;audio-fft;79;59;81;73;49;54;56;78;100;80 05:31:52 INFO - PROCESS | 1661 | |4;audio-oscillator;87;71;86;73;67;69;87;89;59;83 05:31:52 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;88;89;88;90;87;92;100;113;128 05:31:52 INFO - PROCESS | 1661 | |6;imaging-darkroom;117;97;97;96;96;120;148;91;122;148 05:31:52 INFO - PROCESS | 1661 | |7;imaging-desaturate;139;141;88;84;90;82;90;86;92;71 05:31:52 INFO - PROCESS | 1661 | |8;json-parse-financial;55;56;55;56;56;56;56;57;56;57 05:31:52 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;77;70;73;85;76;76;70;72;65;85 05:31:52 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;87;92;93;90;86;78;83;109;110;111 05:31:52 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;143;148;143;155;149;145;154;170;197;161 05:31:52 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;175;173;201;200;173;177;174;174;191;236 05:31:52 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;71;74;72;71;88;68;69;69;70;68 05:31:52 INFO - PROCESS | 1661 | __end_tp_report 05:31:52 INFO - PROCESS | 1661 | __start_cc_report 05:31:52 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,926 05:31:52 INFO - PROCESS | 1661 | __end_cc_report 05:31:52 INFO - PROCESS | 1661 | __startTimestamp1476793912584__endTimestamp 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | ------- Summary: start ------- 05:31:52 INFO - PROCESS | 1661 | Number of tests: 14 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:117.10 Median:118.00 stddev:3.84 (3.3%) stddev-sans-first:1.56 05:31:52 INFO - PROCESS | 1661 | Values: 107.0 120.0 119.0 118.0 116.0 118.0 117.0 118.0 121.0 117.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:124.70 Median:116.00 stddev:19.39 (16.7%) stddev-sans-first:19.40 05:31:52 INFO - PROCESS | 1661 | Values: 143.0 116.0 114.0 148.0 115.0 116.0 115.0 114.0 163.0 103.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:204.50 Median:199.50 stddev:28.08 (14.1%) stddev-sans-first:29.65 05:31:52 INFO - PROCESS | 1661 | Values: 197.0 194.0 181.0 226.0 198.0 198.0 206.0 201.0 171.0 273.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:70.90 Median:75.50 stddev:15.92 (21.1%) stddev-sans-first:16.61 05:31:52 INFO - PROCESS | 1661 | Values: 79.0 59.0 81.0 73.0 49.0 54.0 56.0 78.0 100.0 80.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:77.10 Median:84.50 stddev:10.55 (12.5%) stddev-sans-first:10.56 05:31:52 INFO - PROCESS | 1661 | Values: 87.0 71.0 86.0 73.0 67.0 69.0 87.0 89.0 59.0 83.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:96.40 Median:88.50 stddev:13.69 (15.5%) stddev-sans-first:14.25 05:31:52 INFO - PROCESS | 1661 | Values: 89.0 88.0 89.0 88.0 90.0 87.0 92.0 100.0 113.0 128.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:113.20 Median:93.50 stddev:21.52 (23.0%) stddev-sans-first:22.79 05:31:52 INFO - PROCESS | 1661 | Values: 117.0 97.0 97.0 96.0 96.0 120.0 148.0 91.0 122.0 148.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:96.30 Median:87.00 stddev:23.78 (27.3%) stddev-sans-first:19.57 05:31:52 INFO - PROCESS | 1661 | Values: 139.0 141.0 88.0 84.0 90.0 82.0 90.0 86.0 92.0 71.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:56.00 Median:56.00 stddev:0.67 (1.2%) stddev-sans-first:0.60 05:31:52 INFO - PROCESS | 1661 | Values: 55.0 56.0 55.0 56.0 56.0 56.0 56.0 57.0 56.0 57.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:74.90 Median:76.00 stddev:6.40 (8.4%) stddev-sans-first:6.75 05:31:52 INFO - PROCESS | 1661 | Values: 77.0 70.0 73.0 85.0 76.0 76.0 70.0 72.0 65.0 85.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:93.90 Median:86.50 stddev:11.93 (13.8%) stddev-sans-first:12.39 05:31:52 INFO - PROCESS | 1661 | Values: 87.0 92.0 93.0 90.0 86.0 78.0 83.0 109.0 110.0 111.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:156.50 Median:154.50 stddev:16.59 (10.7%) stddev-sans-first:16.86 05:31:52 INFO - PROCESS | 1661 | Values: 143.0 148.0 143.0 155.0 149.0 145.0 154.0 170.0 197.0 161.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:187.40 Median:184.00 stddev:20.42 (11.1%) stddev-sans-first:21.16 05:31:52 INFO - PROCESS | 1661 | Values: 175.0 173.0 201.0 200.0 173.0 177.0 174.0 174.0 191.0 236.0 05:31:52 INFO - PROCESS | 1661 | 05:31:52 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.00 Median:71.00 stddev:5.93 (8.3%) stddev-sans-first:6.27 05:31:52 INFO - PROCESS | 1661 | Values: 71.0 74.0 72.0 71.0 88.0 68.0 69.0 69.0 70.0 68.0 05:31:52 INFO - PROCESS | 1661 | -------- Summary: end -------- 05:31:52 INFO - PROCESS | 1661 | 05:31:53 INFO - TEST-INFO | 1661: exit 0 05:31:53 INFO - TEST-OK | kraken | took 59857ms 05:31:53 INFO - SUITE-END | took 1175s 05:31:53 INFO - Completed test suite (00:19:35) 05:31:53 INFO - javascript benchmark 05:31:53 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7347.4120115398455, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2840.9857950710243, 2889.5521194214903, 3273.9801845914503, 3304.917377065573, 3331.550240717502, 5592.496675299222, 5601.747921343539, 5602.943970560298, 5611.298587676541, 5744.224529688491, 5043.218301163325, 5519.227308176858, 5646.463585959327, 5658.151277308406, 5680.0627896397045, 5121.718305493197, 5147.536721695046, 5160.845174644766, 5247.632665713401, 5262.552683021929, 5202.843914682566, 5238.3975842778, 5245.186996015624, 5261.854035574504, 5263.842084737464, 4227.682923780729, 4403.691741578091, 5068.391792984866, 5096.541311282002, 5240.0, 5091.032703786269, 5104.927965127327, 5159.0, 5224.216367544861, 5267.841964741064, 5131.255967884673, 5143.151380022292, 5159.097157997373, 5178.818741344035, 5196.8960620787375, 5530.6128571000045, 5534.75093620788, 5715.885682286373, 5723.742431590588, 5732.971335143297, 5198.2722418861385, 5861.4724674779045, 6085.513158947318, 6098.57309988301, 6130.826205142282, 6204.937950620481, 6207.099970504297, 6212.0, 6224.937750622526, 6247.9062814057825, 5693.857653558694, 5697.800576979786, 5698.34469036062, 5708.429157084258, 5726.284214473191, 6163.19878415803, 6175.814725558241, 6196.721147548371, 6214.067889816563, 6236.095766113888, 6004.7089875676065, 6059.7273122709585, 6063.636181829104, 6068.605540639845, 6091.878162436727, 6039.7282122304605, 6206.627602343783, 6212.1303017577575, 6223.688815559204, 6239.906401403982, 6072.36240194782, 6096.889554637438, 6103.298120716086, 6128.693565321716, 6184.6598437085495, 6129.2032035835055, 6176.629402235881, 6181.134641150243, 6192.81421557354, 6201.286852012076, 4825.613950883921, 4879.731614761151, 4885.267209918541, 4885.853424397274, 4894.706317620954, 4328.48058233014, 4370.650347972218, 4373.540778218305, 4381.9342709859375, 4443.02253504235, 5691.402402747734, 5737.655740655574, 5747.08046712524, 5757.309122905278, 5763.135529670583, 5890.293164820249, 6228.096925945712, 6308.653024083627, 6319.715612797435, 6325.114483972247, 4761.619070474354, 5335.253064570963, 5553.250311208016, 5641.830745077655, 5773.913391299134, 4178.811953462102, 5231.843044708665, 5347.385050719139, 5381.75782089799, 5392.164214546751, 6269.247690277196, 6290.779822706183, 6303.0, 6303.716332765037, 6314.431701146918, 5121.231815227746, 6036.517078633787, 6146.846328841814, 6251.374862513712, 6257.5932564383165, 6228.314885362516, 6265.373462653698, 6289.559730818799, 6301.243850737941, 6326.620402775664, 6086.482648974887, 6126.663033533198, 6169.598976066542, 6171.84570385726, 6212.161358216583, 6095.7708482108, 6110.052941794029, 6258.968705156627, 6278.183836101278, 6290.968545157245, 5981.641101533748, 6008.969955150196, 6037.426444487766, 6099.067674246629, 6126.517673797367, 5878.823635290948, 6034.547408944434, 6065.0, 6070.757169713339, 6095.207623008981, 4677.929831052469, 5786.479216870502, 5791.913121303268, 5835.824925252249, 5948.672822994604, 6028.216331876828, 6064.151018857452, 6077.20996270482, 6083.482903953213, 6089.451949324582, 5840.386759390372, 5851.590388672752, 5869.53043756507, 5886.646801191942, 5900.527957763283], "value": 5597.550563402042, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [470.3250835051701, 492.1682356816978, 516.9870753231171, 529.105811179107, 538.7737150396833, 39481.80259098704, 40896.795516022416, 41053.0, 41086.383704244385, 41183.0, 43266.134677306516, 43700.0, 43790.34314485277, 43816.685499435065, 43821.34267985983, 9513.904860951407, 9546.61813527458, 9662.227021838271, 9673.661421850236, 9689.515524223796, 9282.329180747463, 9432.905670943272, 9495.43027418357, 9565.473898935556, 9591.280653950947, 9502.714918552454, 9549.713508594754, 9554.187894029015, 9558.474283914382, 9561.760955976122, 9447.338686291961, 9520.381175223589, 9547.713568592953, 9578.281628877861, 9583.089606487372, 9219.907800921972, 9274.53627318638, 9333.533323333806, 9355.625774968994, 9362.344635875492, 41234.793826030975, 41402.79298603518, 41410.0, 41432.79283603562, 41584.37623435651, 3347.0, 3351.6815902489225, 3391.593008838955, 3467.861285548575, 3864.0, 30311.393772124433, 30742.0, 30802.53796193059, 30887.691123088705, 31037.534436983464, 3203.85582648779, 3230.4185246655593, 3233.369492948876, 3257.8696852125886, 3328.0348698877297, 290606.0, 357385.0, 358538.9999999974, 358972.0, 372464.0, 58711.41288587145, 58879.0, 59158.40841591615, 59178.0, 59482.40517594855, 61750.0737488938, 62819.057714134324, 62831.685841571416, 63027.73944521084, 63128.68435657792, 12758.043146763845, 14336.21150836714, 15043.623909402133, 16123.83876161247, 16349.264283107288, 11936.224145430693, 12209.877901220874, 12618.92739117167, 12700.983921286275, 12703.428345724464, 14998.100113993196, 15400.15299158557, 15818.841811581737, 15946.681066378607, 15985.920070399807, 6485.416312531895, 6561.80314590563, 7027.227005029443, 7109.862422012557, 7115.399035217035, 775.8370742144212, 833.083608031173, 964.1997142371814, 970.96601618943, 1036.9688909332733, 28928.0, 29907.0, 30023.998486402637, 30565.0, 32000.519992200134, 1008.4403156248206, 1053.1627356251797, 1098.1763677242068, 1108.578740078765, 1127.3236058364917, 14930.70138597222, 15458.922705386402, 15645.174980901806, 15872.0, 15912.363505459884, 626504.0, 633468.0, 634177.0, 637632.0, 637766.0, 58791.41208587945, 58814.41185588175, 58969.0, 58985.11522327083, 59299.0, 1348.858369871169, 1366.180291824897, 1379.2483096712315, 1383.9169649820842, 1389.020740378031, 24665.506689865742, 28427.857860710563, 29704.851475742482, 29846.850765746032, 30344.393112137193, 49600.50399495959, 50039.0, 50693.0, 50701.23948140855, 50747.0, 550886.0, 563751.0, 566739.0, 569323.0, 570026.2480313911, 39712.60287397089, 40267.798661006505, 40544.18911621692, 41275.38086928638, 41513.37729934114, 46442.6067217984, 47164.0, 47696.0, 48275.75862120667, 48297.5170248293, 39351.80324098361, 39448.802755986035, 39544.40683389694, 40556.0, 41284.38073428841, 30248.395032099674, 38423.04442811369, 38592.80703596464, 38607.0, 39958.0, 37741.24517509579, 38438.0, 38544.0, 38680.806595967966, 38733.80633096817, 33061.33877322392, 34061.97814065582, 34736.131596710286, 34801.82599086988, 34886.12784680403, 25015.249542513753, 25094.49811003806, 25714.0, 25868.22395328143, 25964.350891227114], "value": 19812.59040790776, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [321.8326470235477, 327.57251786418715, 371.9386301260292, 376.7475791219882, 388.9786061766603, 16077.919610401961, 19273.0, 19956.60086798265, 20431.97840107996, 20633.27783527579, 19330.90334548329, 20288.985550722442, 20313.984300784938, 20525.589488210226, 20676.68984965223, 4739.597134243593, 5222.712750798704, 6037.939620603804, 6043.063325184581, 6067.696615169246, 4149.439825623533, 4382.956170438287, 5834.183214349994, 5893.499052580536, 6244.656543890084, 6534.281229064799, 6620.834479138036, 6623.9006414903815, 6625.701843417034, 6630.6021638701595, 6340.207474065742, 6563.113979612739, 6594.571352862049, 6596.86806263872, 6630.801075967729, 5392.757325920304, 5660.405657405954, 5680.289963754531, 5682.545396368322, 5709.143628455723, 25401.110961116457, 25420.49159016828, 25454.109106181193, 25551.488970220493, 25612.743872561223, 2029.2998915374171, 2186.9343919682433, 2332.137876070941, 2373.335466069503, 2798.965707937048, 14945.775813362809, 15412.0, 15535.0, 15535.378584856593, 16041.91979040109, 1849.5838436351848, 1867.4771064102074, 2023.4132101690493, 2051.9692204616945, 2127.617028934791, 110011.44994275058, 116205.99999999916, 130018.0, 137219.31390343086, 138545.6145438543, 20410.69383959242, 20442.28452004173, 21114.577708446053, 21192.576148477252, 21344.916698740784, 34558.82720586381, 34825.12937176591, 34989.30021399558, 35026.64973350285, 35140.29719405597, 10545.57817687284, 10642.25504214713, 10866.293690910066, 10953.561857525769, 11048.116150707923, 8729.388942773945, 8877.467351958903, 8878.600462979182, 9039.728808135635, 9068.954655226682, 7961.681532738626, 8159.184081591793, 9679.516024198761, 9897.80204395908, 9938.850917236246, 1584.9841501585067, 1645.1691895592649, 1671.4150047483283, 3790.583035866108, 3916.4321173429694, 798.616664001271, 852.5737131434283, 911.2026976395654, 941.1929096135436, 972.5429048347265, 19267.710984335245, 21030.78969210319, 21232.0, 21359.551960174238, 21468.570628587342, 878.2227728460358, 919.1176470588176, 927.5501381829807, 954.9379290346105, 983.4590974963742, 10849.457527123612, 11206.38364889939, 11263.380514071805, 11292.491837867316, 11324.094072474183, 585431.0, 585523.0723846353, 590727.0, 596433.0, 597155.0, 132457.67542324454, 132677.0, 134558.0, 135316.32341838424, 137640.0, 946.5645802930729, 958.5351104714208, 983.5033308179467, 987.6247026130025, 991.4596544883057, 15590.0, 18597.535061623566, 18646.0, 18722.940476547297, 18794.0, 55018.0, 55573.44426555844, 56167.15749263683, 56366.87266254734, 56719.14921276267, 75770.24229757632, 77738.6113069431, 80108.99727506626, 82232.58883705543, 83897.0, 6212.502999759918, 6334.303226645157, 7534.246575342422, 8210.0, 8244.051934027664, 10332.793344133226, 12416.81374779397, 12880.935595322338, 13297.867021329663, 13387.732245354844, 10451.964728108323, 11226.045786108272, 11458.541658333574, 11589.014933730727, 11669.35818529989, 9772.853407198756, 10017.198624109908, 10447.0, 11044.944775276394, 11359.48882300282, 11490.482928268415, 11559.306441613193, 11787.057035436974, 11840.0, 11845.881541184477, 7330.79041958071, 8117.715879944174, 8235.95882020586, 8296.751097467086, 8304.87542686848, 8880.467171969702, 11295.322280662858, 11710.180287379802, 11764.294142351486, 11787.764244715228], "value": 10472.660099796389, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1502.8722558582517, 1509.2227502836042, 1765.5586103474132, 1880.6050729346837, 1912.2446633579727, 7944.721934732285, 8231.094579596254, 8447.15528447155, 8991.426500362435, 9034.457932524045, 8433.072362040171, 8737.301015918727, 8761.342899282547, 8822.073682263366, 8841.5579221039, 2308.74603793583, 2349.6827928229727, 2353.9058437662475, 2356.3284463927785, 2373.2998765364173, 2342.613468777658, 2360.905563777447, 2385.320183747632, 2388.259639511757, 2394.6048901931163, 2300.1949317738827, 2343.1564636730764, 2367.112332875172, 2376.394019525019, 2386.1052105460453, 2333.218371845434, 2335.0076217607534, 2354.387859156623, 2358.351453350334, 2365.7161140663056, 2231.62062449384, 2244.55108978205, 2247.539254452833, 2252.1441852096264, 2255.165588732163, 8798.648054077828, 8809.691660791841, 8861.822763544758, 8863.980642226097, 8909.109089091038, 4551.203539380595, 4715.8210447388155, 5198.960207958424, 5361.892762144775, 5366.007288651573, 3478.286951175003, 3509.4560343146854, 3623.3296840084668, 3634.6547078027547, 3644.8131121327256, 1031.4378663628265, 1066.0884943373383, 1069.3209811769582, 1072.975308580304, 1089.096050278267, 10474.371537707762, 11460.140489463323, 11537.307761534252, 11549.884501154966, 11614.4192790361, 4128.896777580585, 4543.114092751948, 4633.096546173531, 4810.8797280068275, 4811.879703007453, 9804.852927206097, 9870.309078364446, 9871.407715536947, 9878.654247101316, 9914.107730304308, 5350.785968561214, 5381.219723140122, 5384.515393614593, 5391.137418013178, 5420.024395608828, 4415.712978656377, 4595.931061034087, 4718.787654555554, 4839.951600484021, 4858.878528036827, 3846.6922646188236, 3878.8060596970604, 3969.6030396960073, 3971.252648834561, 4031.1937612477623, 1290.7547565962436, 1294.2816736711027, 1295.6307452376027, 1298.448159532195, 1307.0066749270434, 906.7551761024619, 923.70903165504, 929.2148134826061, 937.4609599480326, 952.1620973543376, 5312.548433383128, 5897.882042359214, 5918.62514541927, 6048.123022161762, 6090.63456192621, 430.18265295937346, 432.11847830426206, 435.86052463212104, 436.67031391299366, 436.9300911854088, 3419.9145021374666, 3738.6822120120096, 3757.666028559822, 4011.659008984269, 4038.939415908705, 10331.22515811302, 10547.630832920811, 10665.680029599125, 10674.092702120406, 10681.359118452918, 9580.760480987892, 10603.681889543326, 10604.787904241872, 10635.255532112678, 10648.615679961555, 7951.483153594998, 8249.917500825035, 8255.876161857577, 8280.378971577156, 8287.088420273885, 5817.970910145422, 5851.853703657443, 5898.380670029585, 6019.819405417845, 6180.381961803783, 8759.868601971102, 9988.750281242736, 9996.500174991366, 10098.747531311776, 10131.64539241123, 8244.422890397615, 9524.333296669167, 9843.950780246052, 10062.69811905644, 10180.697215696817, 6484.0, 6821.727130914708, 7056.894146587908, 7199.460040496879, 7333.303336183054, 8136.267735903797, 8274.544900030556, 8346.207110324509, 8347.499150051017, 8356.582170891552, 7309.122905251404, 7537.849243015218, 7538.509996850187, 7595.050618672666, 7596.08846938371, 7329.853402931805, 7377.631118444164, 7446.994655721408, 7508.2867127622785, 7528.623568821647, 6838.8290292741085, 6877.690503927235, 6996.790096297119, 7040.331168538981, 7074.0, 4909.901801963869, 4992.950070499249, 5060.797568097235, 5061.468545802638, 5081.9745901271735, 5039.2944987702485, 5058.873528161826, 5181.1969144782615, 5202.1416466282635, 5240.135377662643], "value": 4472.308420278117, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.952128616849, 302.6564848896502, 309.8667572943634, 313.5297054418372, 316.80358177929685, 4992.651983964337, 5360.865978350544, 5371.355437347524, 5567.582431317652, 5709.77160913563, 4258.552851950547, 5220.92168617471, 5482.656749096467, 5487.259220005298, 5498.780048798043, 3917.3928041153667, 4186.964450773188, 4202.810873510699, 4208.327711902948, 4213.565943755844, 3847.095687634611, 4133.462649855514, 4166.958330416703, 4191.308434108369, 4226.957730422702, 4119.876403707894, 4125.773082480462, 4160.188763191179, 4168.5623009584015, 4198.5591512891015, 3133.780635355526, 4113.4241206231, 4116.91766164675, 4131.834726610932, 4177.686673499469, 3577.2130131371055, 3591.3356029134693, 3594.964050359489, 3620.9275814483826, 3645.5989841117457, 5252.737363131828, 5365.32475254448, 5410.594205434608, 5425.6202065855405, 5449.373322067973, 3957.663598594123, 4739.857804265877, 4748.596369308613, 4751.121042607094, 4755.619550435957, 4871.391076115486, 5059.544640982293, 5808.651480911159, 5823.621464604787, 5828.504577110951, 2987.6414830220515, 3291.588551431071, 3334.9666503334897, 3480.868717666743, 3645.2345007548447, 5704.857378565527, 6131.601445906002, 6160.661163636043, 6166.238594457377, 6184.412480814315, 3949.1311911379453, 3958.980205099014, 3985.3025720499377, 3986.2824691555797, 3998.9400158997637, 3714.294284086015, 3996.800159991989, 4036.798160091984, 4044.4742183515955, 4051.756894586334, 4730.526947305242, 4734.242521196592, 4738.4550776660435, 4742.79460051071, 4756.334113224151, 4959.578435832923, 5655.519280861173, 5848.736806843703, 5875.265591801025, 5877.412258774174, 1286.7812471879804, 1317.7298653775954, 1337.1909994453215, 1343.4693296147966, 1365.6381059019368, 890.3589415620743, 899.1053901368181, 916.225789208118, 920.6236676169036, 924.3638759395966, 4435.756033418128, 5182.6372153949615, 5450.863728406822, 5453.509184173444, 5478.260434841245, 5122.078025955364, 5476.17857321405, 5957.017092179828, 6010.338862725097, 6011.368806275365, 4470.687051906335, 4795.712257264575, 5099.566536844335, 5251.737413129328, 5281.973590132025, 2639.5512762830367, 2656.4288677934337, 2658.218993274511, 2663.7735792457856, 2685.436058427752, 4303.783551470995, 4329.891752706145, 5189.610779191576, 5199.402068762065, 5226.346706661668, 4540.091981603692, 4886.804527818847, 4941.888075183055, 5096.986690257328, 5177.508136727005, 4550.203714349976, 4936.432310284364, 4976.601871850244, 5223.3883058470765, 5227.790888364423, 4809.426956578214, 4840.685355451885, 5071.366079240095, 5089.440161582297, 5117.897642047213, 4123.422720819028, 4577.542245775396, 4663.790129444251, 4964.833264182987, 5030.748462576783, 4347.369631403492, 4482.551744825491, 5161.535461808456, 5199.870003249949, 5256.342957130359, 3270.936945492677, 3452.913677158091, 3482.2316258573987, 3490.4118626024588, 3493.0568746438826, 4390.499737768812, 4485.863686079486, 4752.025834703808, 4789.257665061921, 5119.950410165832, 3915.4518367428013, 4226.949929284233, 4902.583280421061, 4911.607071434348, 4921.926171107508, 4409.382686423967, 4550.863474095782, 4884.389451318585, 4894.314795928502, 4927.679700819435, 4353.434053573015, 4395.9340609891515, 4445.799939002817, 4796.472388037383, 4822.903541929071, 4301.214995776718, 4463.852983646586, 4525.524819893863, 4554.337666751678, 4955.456848353445, 4138.91722165561, 4397.504848351576, 4496.685232033857, 4775.785089670903, 4790.401199850019], "value": 3911.5207384821624, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [679.8300424893777, 710.594960872303, 714.9356557909787, 743.5464366736293, 794.8847417124515, 12643.873561264385, 14087.29563521825, 15303.540893773177, 15972.201389930515, 16191.757123643125, 12398.504059837596, 14602.853971460283, 14721.190334531597, 14725.926370368159, 14755.40978360867, 708.1785129250071, 723.7286017743346, 865.3726048614735, 883.4213590098464, 890.1410139215656, 741.4735537768191, 845.5138295480124, 870.5342641686699, 887.6050157679803, 907.8774365460678, 954.4464210757778, 955.2310390135938, 955.2358113509199, 955.2596737528436, 955.7037318431273, 928.5078908178676, 946.0633972367341, 950.9381890177116, 951.619352259095, 953.3755390219452, 695.2352412346379, 703.9859202815966, 711.6370650967992, 711.7686751805684, 714.1430283659578, 14565.70868582633, 14606.780898286428, 14819.259037048105, 14831.184284864328, 14898.329575169144, 14264.643383915381, 14415.279236038261, 14425.78361324581, 14554.272286385638, 14556.636084097981, 11557.364344961024, 11626.360550169737, 11638.359890206035, 11717.882821171765, 11814.232074915102, 3672.320620685155, 3805.4482100095606, 3817.675497582709, 3823.50294461721, 3940.7635541867294, 87446.0, 91385.54307228488, 91683.0, 104581.99999999924, 116622.0, 10942.726431839268, 11066.170037247077, 11104.389258590694, 11150.776984460266, 11178.27341222818, 21955.0, 22064.88967555152, 22137.66793498099, 22145.224917127824, 22198.0, 7718.961405192938, 7864.213578642204, 7910.802229944183, 8010.639521221559, 8153.673853045931, 4218.114196018809, 4308.159908817751, 4324.827006919751, 4333.826646934087, 4364.8254069837485, 3571.4107172316276, 3708.35103856824, 3709.9443508347395, 3712.6101759315425, 3859.864904728321, 639.389383139101, 709.6806437103324, 712.2023333866, 720.7693538067768, 726.2410780734145, 589.9115132730125, 650.2067477677226, 674.3795707948699, 681.2948598200838, 682.6893763337769, 8933.677521842628, 9123.178913897782, 9252.444853308823, 9457.621695132118, 9673.467959262309, 515.4561937156336, 599.8320470268332, 630.6941133550224, 641.5060403489288, 658.7397977798743, 3986.461827653288, 4103.651189648914, 4163.83344666216, 4168.374743788456, 4310.762908040025, 87545.56227218822, 98852.0, 98860.50569747249, 144180.27909860385, 161225.0, 106016.0, 126061.0, 127047.36476317744, 127491.0, 127686.0, 69033.65483172552, 73401.0, 81815.9999999988, 97737.0, 107798.46100769447, 7226.4218862491925, 9780.85328720084, 9833.4099954003, 9855.655052073143, 10058.748531286483, 59971.700141499015, 64184.03723944051, 66513.33716657122, 67241.0, 68552.31447685459, 97812.0, 100286.49856750961, 101892.0, 102604.0, 103081.23809476278, 12874.291913944822, 13456.86543134556, 13476.595702128952, 13867.79198312006, 14453.566393008226, 14531.34608942579, 14647.853521465075, 14738.484153054591, 14756.409743610135, 14908.403663853325, 11185.552577896793, 13356.76976700046, 13402.32988350559, 13437.731245375233, 13649.863501365257, 12270.693232669255, 13012.089153759525, 13609.863901360859, 13691.041627086008, 14061.789073163705, 13420.261885596386, 13423.932880335926, 13577.0, 13854.722905541632, 13948.511802087283, 10061.798764024825, 10995.670129896116, 11157.609483668033, 11375.829362559403, 11487.483063262007, 11346.149038821955, 11476.827847582459, 11507.654770356903, 11608.6517404478, 11711.0], "value": 7742.516602673415, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.9000000000003, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 120.0, 119.0, 118.0, 116.0, 118.0, 117.0, 118.0, 121.0, 117.0], "value": 117.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 116.0, 114.0, 148.0, 115.0, 116.0, 115.0, 114.0, 163.0, 103.0], "value": 124.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 194.0, 181.0, 226.0, 198.0, 198.0, 206.0, 201.0, 171.0, 273.0], "value": 204.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 59.0, 81.0, 73.0, 49.0, 54.0, 56.0, 78.0, 100.0, 80.0], "value": 70.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 86.0, 73.0, 67.0, 69.0, 87.0, 89.0, 59.0, 83.0], "value": 77.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 89.0, 88.0, 90.0, 87.0, 92.0, 100.0, 113.0, 128.0], "value": 96.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 97.0, 97.0, 96.0, 96.0, 120.0, 148.0, 91.0, 122.0, 148.0], "value": 113.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 141.0, 88.0, 84.0, 90.0, 82.0, 90.0, 86.0, 92.0, 71.0], "value": 96.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 56.0, 55.0, 56.0, 56.0, 56.0, 56.0, 57.0, 56.0, 57.0], "value": 56.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 70.0, 73.0, 85.0, 76.0, 76.0, 70.0, 72.0, 65.0, 85.0], "value": 74.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 92.0, 93.0, 90.0, 86.0, 78.0, 83.0, 109.0, 110.0, 111.0], "value": 93.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 148.0, 143.0, 155.0, 149.0, 145.0, 154.0, 170.0, 197.0, 161.0], "value": 156.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 173.0, 201.0, 200.0, 173.0, 177.0, 174.0, 174.0, 191.0, 236.0], "value": 187.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 74.0, 72.0, 71.0, 88.0, 68.0, 69.0, 69.0, 70.0, 68.0], "value": 72.0, "unit": "score"}]}]} 05:31:53 INFO - Return code: 0 05:31:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:31:53 ERROR - # TBPL SUCCESS # 05:31:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:31:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:31:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:31:53 INFO - 2016-10-18 05:31:53.926 screenresolution[1666:10359] starting screenresolution argv=screenresolution get 05:31:53 INFO - 2016-10-18 05:31:53.929 screenresolution[1666:10359] Display 0: 1600x1200x32@60 05:31:53 INFO - 2016-10-18 05:31:53.935 screenresolution[1667:10362] starting screenresolution argv=screenresolution list 05:31:53 INFO - Available Modes on Display 0 05:31:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:31:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:31:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:31:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:31:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:31:54 INFO - Intel Iris: 05:31:54 INFO - Chipset Model: Intel Iris 05:31:54 INFO - Type: GPU 05:31:54 INFO - Bus: Built-In 05:31:54 INFO - VRAM (Dynamic, Max): 1536 MB 05:31:54 INFO - Vendor: Intel (0x8086) 05:31:54 INFO - Device ID: 0x0a2e 05:31:54 INFO - Revision ID: 0x0009 05:31:54 INFO - Displays: 05:31:54 INFO - Display: 05:31:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:31:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:31:54 INFO - Display Serial Number: 1600x1200 60 05:31:54 INFO - Main Display: Yes 05:31:54 INFO - Mirror: Off 05:31:54 INFO - Online: Yes 05:31:54 INFO - Rotation: Supported 05:31:54 INFO - Return code: 0 05:31:54 INFO - Running post-action listener: _resource_record_post_action 05:31:54 INFO - [mozharness: 2016-10-18 12:31:54.168339Z] Finished run-tests step (success) 05:31:54 INFO - Running post-run listener: _resource_record_post_run 05:31:54 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 34128384; Write bytes: 710378496; Read time: 320; Write time: 1964 05:31:54 INFO - TinderboxPrint: CPU usage
55.0% 05:31:54 INFO - TinderboxPrint: I/O read bytes / time
34,128,384 / 320 05:31:54 INFO - TinderboxPrint: I/O write bytes / time
710,378,496 / 1,964 05:31:54 INFO - TinderboxPrint: CPU idle
2,178.1 (45.0%) 05:31:54 INFO - TinderboxPrint: CPU system
989.2 (20.4%) 05:31:54 INFO - TinderboxPrint: CPU user
1,674.3 (34.6%) 05:31:54 INFO - TinderboxPrint: Swap in / out
436,666,368 / 0 05:31:54 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 403859968; Write bytes: 398919680; Read time: 29694; Write time: 783 05:31:54 INFO - run-tests - Wall time: 1177s; CPU: 55.0%; Read bytes: 30085632; Write bytes: 299179008; Read time: 289; Write time: 1143 05:31:54 INFO - Running post-run listener: _upload_blobber_files 05:31:54 INFO - Blob upload gear active. 05:31:54 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:31:54 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 05:31:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:31:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:31:55 INFO - (blobuploader) - INFO - Open directory for files ... 05:31:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:31:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:31:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:56 INFO - (blobuploader) - INFO - Done attempting. 05:31:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:31:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:57 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:31:57 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:57 INFO - (blobuploader) - INFO - Done attempting. 05:31:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:31:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:31:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:31:59 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:31:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:31:59 INFO - (blobuploader) - INFO - Done attempting. 05:31:59 INFO - (blobuploader) - INFO - Iteration through files over. 05:31:59 INFO - Return code: 0 05:31:59 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:31:59 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:31:59 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d9cb028a1fb49499ab2dbdd2bca539176ea16dc4ebfa0baeb47b8cba024780dfa0c8af757d62af1c1e833c893be54e338f37a507de3a3850e2eb53c93d17ea9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fd17c51ec035f061b4fea2af7a8ee6be95c4726f39769633a3583fbb167c55b3a186a0fd44b6e745fe6bae716ae20357b1563cb8911664dab142dfb3e5d156", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4b1ee1f64eca22dd53b577dc9da5bc9c1bad2b509ee94dfae941feefd5e88ea5ef4bdd8610dc9f85bd4c6897bfa90d25084b024c90646aafb861321efb5fa8"} 05:31:59 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:31:59 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:31:59 INFO - Contents: 05:31:59 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d9cb028a1fb49499ab2dbdd2bca539176ea16dc4ebfa0baeb47b8cba024780dfa0c8af757d62af1c1e833c893be54e338f37a507de3a3850e2eb53c93d17ea9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fd17c51ec035f061b4fea2af7a8ee6be95c4726f39769633a3583fbb167c55b3a186a0fd44b6e745fe6bae716ae20357b1563cb8911664dab142dfb3e5d156", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4b1ee1f64eca22dd53b577dc9da5bc9c1bad2b509ee94dfae941feefd5e88ea5ef4bdd8610dc9f85bd4c6897bfa90d25084b024c90646aafb861321efb5fa8"} 05:31:59 INFO - Running post-run listener: copy_logs_to_upload_dir 05:31:59 INFO - Copying logs to upload dir... 05:31:59 INFO - mkdir: /builds/slave/test/build/upload/logs 05:31:59 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.130149 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-18 05:31:59.730600) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 05:31:59.734594) ========= 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.ICFvWuDl3a/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.tneSoEpEjf/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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/Autoland/sha512/d9cb028a1fb49499ab2dbdd2bca539176ea16dc4ebfa0baeb47b8cba024780dfa0c8af757d62af1c1e833c893be54e338f37a507de3a3850e2eb53c93d17ea9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fd17c51ec035f061b4fea2af7a8ee6be95c4726f39769633a3583fbb167c55b3a186a0fd44b6e745fe6bae716ae20357b1563cb8911664dab142dfb3e5d156", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4b1ee1f64eca22dd53b577dc9da5bc9c1bad2b509ee94dfae941feefd5e88ea5ef4bdd8610dc9f85bd4c6897bfa90d25084b024c90646aafb861321efb5fa8"} build_url:https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011219 build_url: 'https://queue.taskcluster.net/v1/task/GuDiu7xcSNGY_Il70-utBQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d9cb028a1fb49499ab2dbdd2bca539176ea16dc4ebfa0baeb47b8cba024780dfa0c8af757d62af1c1e833c893be54e338f37a507de3a3850e2eb53c93d17ea9a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/01fd17c51ec035f061b4fea2af7a8ee6be95c4726f39769633a3583fbb167c55b3a186a0fd44b6e745fe6bae716ae20357b1563cb8911664dab142dfb3e5d156", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2f4b1ee1f64eca22dd53b577dc9da5bc9c1bad2b509ee94dfae941feefd5e88ea5ef4bdd8610dc9f85bd4c6897bfa90d25084b024c90646aafb861321efb5fa8"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-18 05:31:59.767071) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:31:59.767409) ========= 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.ICFvWuDl3a/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.tneSoEpEjf/Listeners TMPDIR=/var/folders/3x/thk4_hjj2mv64h_wz8cbb_1h00000w/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.004599 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-18 05:31:59.816965) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 05:31:59.817567) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-18 05:31:59.818147) ========= ========= Total master_lag: 0.17 =========