builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0330 starttime: 1476407996.03 results: success (0) buildid: 20161013162335 builduid: 35a19e7c7c9a4ab9991ac1249c5cdb81 revision: 96f862cb3d91729aeaef135b9a9c62d726d47053 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.027356) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.027784) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.028074) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.051088) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.051366) ========= 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.POHXzahlSD/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.6eAwUATTHC/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-13 18:19:56-- 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% 164M=0s 2016-10-13 18:19:56 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.081007 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.153484) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.153776) ========= 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.POHXzahlSD/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.6eAwUATTHC/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.021888 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:56.202298) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 18:19:56.202631) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 96f862cb3d91729aeaef135b9a9c62d726d47053 --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 96f862cb3d91729aeaef135b9a9c62d726d47053 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.POHXzahlSD/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.6eAwUATTHC/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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-13 18:19:56,283 truncating revision to first 12 chars 2016-10-13 18:19:56,283 Setting DEBUG logging. 2016-10-13 18:19:56,283 attempt 1/10 2016-10-13 18:19:56,283 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/96f862cb3d91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-13 18:19:57,482 unpacking tar archive at: autoland-96f862cb3d91/testing/mozharness/ program finished with exit code 0 elapsedTime=1.473064 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-13 18:19:57.697031) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:57.697428) ========= script_repo_revision: 96f862cb3d91729aeaef135b9a9c62d726d47053 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:57.697813) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:57.698094) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-13 18:19:57.716090) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-13 18:19:57.716418) ========= /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.POHXzahlSD/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.6eAwUATTHC/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:19:57 INFO - MultiFileLogger online at 20161013 18:19:57 in /builds/slave/test 18:19:57 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 18:19:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:19:57 INFO - {'append_to_log': False, 18:19:57 INFO - 'base_work_dir': '/builds/slave/test', 18:19:57 INFO - 'blob_upload_branch': 'Autoland', 18:19:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:19:57 INFO - 'branch': 'Autoland', 18:19:57 INFO - 'buildbot_json_path': 'buildprops.json', 18:19:57 INFO - 'config_files': ('talos/mac_config.py',), 18:19:57 INFO - 'default_actions': ('clobber', 18:19:57 INFO - 'read-buildbot-config', 18:19:57 INFO - 'download-and-extract', 18:19:57 INFO - 'populate-webroot', 18:19:57 INFO - 'create-virtualenv', 18:19:57 INFO - 'install', 18:19:57 INFO - 'run-tests'), 18:19:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:19:57 INFO - 'download_minidump_stackwalk': True, 18:19:57 INFO - 'download_symbols': 'ondemand', 18:19:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:19:57 INFO - 'tooltool.py': '/tools/tooltool.py', 18:19:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:19:57 INFO - '/tools/misc-python/virtualenv.py')}, 18:19:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:19:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:19:57 INFO - 'installer_path': 'installer.exe', 18:19:57 INFO - 'log_level': 'info', 18:19:57 INFO - 'log_name': 'talos', 18:19:57 INFO - 'log_to_console': True, 18:19:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:19:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:19:57 INFO - 'opt_config_files': (), 18:19:57 INFO - 'pip_index': False, 18:19:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:19:57 INFO - 'cmd': ('bash', 18:19:57 INFO - '-c', 18:19:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:19:57 INFO - 'enabled': True, 18:19:57 INFO - 'halt_on_failure': False, 18:19:57 INFO - 'name': 'check_screen_resolution'},), 18:19:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:19:57 INFO - 'cmd': ('bash', 18:19:57 INFO - '-c', 18:19:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:19:57 INFO - 'enabled': True, 18:19:57 INFO - 'halt_on_failure': False, 18:19:57 INFO - 'name': 'check_screen_resolution'},), 18:19:57 INFO - 'run_cmd_checks_enabled': True, 18:19:57 INFO - 'sps_profile': False, 18:19:57 INFO - 'sps_profile_interval': 0, 18:19:57 INFO - 'suite': 'dromaeojs-e10s', 18:19:57 INFO - 'system_bits': '32', 18:19:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:19:57 INFO - 'title': 't-yosemite-r7-0330', 18:19:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:19:57 INFO - 'use_talos_json': True, 18:19:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:19:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:19:57 INFO - 'work_dir': 'build'} 18:19:57 INFO - [mozharness: 2016-10-14 01:19:57.913426Z] Running clobber step. 18:19:57 INFO - Running pre-action listener: _resource_record_pre_action 18:19:57 INFO - Running main action method: clobber 18:19:57 INFO - rmtree: /builds/slave/test/build 18:19:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:19:59 INFO - Running post-action listener: _resource_record_post_action 18:19:59 INFO - [mozharness: 2016-10-14 01:19:59.901061Z] Finished clobber step (success) 18:19:59 INFO - [mozharness: 2016-10-14 01:19:59.901223Z] Running read-buildbot-config step. 18:19:59 INFO - Running pre-action listener: _resource_record_pre_action 18:19:59 INFO - Running main action method: read_buildbot_config 18:19:59 INFO - Using buildbot properties: 18:19:59 INFO - { 18:19:59 INFO - "project": "", 18:19:59 INFO - "product": "firefox", 18:19:59 INFO - "script_repo_revision": "production", 18:19:59 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 18:19:59 INFO - "repository": "", 18:19:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 18:19:59 INFO - "buildid": "20161013162335", 18:19:59 INFO - "pgo_build": "False", 18:19:59 INFO - "basedir": "/builds/slave/test", 18:19:59 INFO - "buildnumber": 257, 18:19:59 INFO - "slavename": "t-yosemite-r7-0330", 18:19:59 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 18:19:59 INFO - "platform": "yosemite_r7", 18:19:59 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 18:19:59 INFO - "branch": "autoland", 18:19:59 INFO - "repo_path": "integration/autoland", 18:19:59 INFO - "revision": "96f862cb3d91729aeaef135b9a9c62d726d47053", 18:19:59 INFO - "stage_platform": "macosx64", 18:19:59 INFO - "builduid": "35a19e7c7c9a4ab9991ac1249c5cdb81", 18:19:59 INFO - "slavebuilddir": "test" 18:19:59 INFO - } 18:19:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 18:19:59 INFO - Running post-action listener: _resource_record_post_action 18:19:59 INFO - [mozharness: 2016-10-14 01:19:59.904766Z] Finished read-buildbot-config step (success) 18:19:59 INFO - [mozharness: 2016-10-14 01:19:59.904919Z] Running download-and-extract step. 18:19:59 INFO - Running pre-action listener: _resource_record_pre_action 18:19:59 INFO - Running main action method: download_and_extract 18:19:59 INFO - mkdir: /builds/slave/test/build/tests 18:19:59 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')]} 18:19:59 INFO - trying https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 18:19:59 INFO - Downloading https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/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 18:19:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/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 18:20:00 INFO - Downloaded 1199 bytes. 18:20:00 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 18:20:00 INFO - Using the following test package requirements: 18:20:00 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:20:00 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 18:20:00 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 18:20:00 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'jsshell-mac.zip'], 18:20:00 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 18:20:00 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 18:20:00 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 18:20:00 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 18:20:00 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 18:20:00 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 18:20:00 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 18:20:00 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:20:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:20:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:20:00 INFO - Fetch https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:20:01 INFO - Http code: 200 18:20:01 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 18:20:01 INFO - x-amz-cf-id: q0M-kytfMmMEs3Ew0GpBWioHmSqs3HYXh2NtokVcRJecGXO-gcvQkA== 18:20:01 INFO - x-amz-version-id: fE4yc5sFE4PLxDAB9joTocMJUD5VUZjj 18:20:01 INFO - Expected file size: 16515378 18:20:01 INFO - Obtained file size: 16515378 18:20:08 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 18:20:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 18:20:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:20:08 INFO - Fetch https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 18:20:08 INFO - Http code: 200 18:20:08 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 18:20:08 INFO - x-amz-cf-id: I9xe7IkvTP7YRaOVbLfzuUE3nHtgQ4R-aApsy5rbAX39juvGkFwWnQ== 18:20:08 INFO - x-amz-version-id: fE4yc5sFE4PLxDAB9joTocMJUD5VUZjj 18:20:09 INFO - Expected file size: 16515378 18:20:09 INFO - Obtained file size: 16515378 18:20:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 18:20:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:20:15 INFO - Fetch https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 18:20:16 INFO - Http code: 200 18:20:16 INFO - via: 1.1 455cf6ccbccc261c46d02110de1c0237.cloudfront.net (CloudFront) 18:20:16 INFO - x-amz-cf-id: FgCVR8FuD-4fUKkIlUKTopTO-7IEwXn6m_vHY3U-Tj4q0U1MePNGLA== 18:20:16 INFO - x-amz-version-id: TrN8figpFcf7gU9yWYkbWW5RmlNWo4pv 18:20:16 INFO - Expected file size: 14409006 18:20:16 INFO - Obtained file size: 14409006 18:20: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')]} 18:20:17 INFO - trying https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:20:17 INFO - Downloading https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 18:20:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/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 18:20:19 INFO - Downloaded 111659332 bytes. 18:20:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:20:19 INFO - mkdir: /builds/slave/test/properties 18:20:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:20:19 INFO - Writing to file /builds/slave/test/properties/build_url 18:20:19 INFO - Contents: 18:20:19 INFO - build_url:https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 18:20:20 INFO - Running post-action listener: _resource_record_post_action 18:20:20 INFO - Running post-action listener: set_extra_try_arguments 18:20:20 INFO - [mozharness: 2016-10-14 01:20:20.655198Z] Finished download-and-extract step (success) 18:20:20 INFO - [mozharness: 2016-10-14 01:20:20.655324Z] Running populate-webroot step. 18:20:20 INFO - Running pre-action listener: _resource_record_pre_action 18:20:20 INFO - Running main action method: populate_webroot 18:20:20 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:20:20 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:20:20 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:20:20 INFO - '--tppagecycles', 18:20:20 INFO - '7'], 18:20:20 INFO - 'tests': ['tsvgm']}}, 18:20:20 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:20:20 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:20:20 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:20:20 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:20:20 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:20 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:20:20 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['damp', 'tps']}, 18:20:20 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'tests': ['damp', 'tps']}, 18:20:20 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['dromaeo_dom']}, 18:20:20 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:20:20 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['basic_compositor_video']}, 18:20:20 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:20:20 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['a11yr', 18:20:20 INFO - 'ts_paint', 18:20:20 INFO - 'tpaint', 18:20:20 INFO - 'sessionrestore', 18:20:20 INFO - 'sessionrestore_no_auto_restore', 18:20:20 INFO - 'tabpaint']}, 18:20:20 INFO - 'other-e10s': {'tests': ['a11yr', 18:20:20 INFO - 'ts_paint', 18:20:20 INFO - 'tpaint', 18:20:20 INFO - 'sessionrestore', 18:20:20 INFO - 'sessionrestore_no_auto_restore', 18:20:20 INFO - 'tabpaint']}, 18:20:20 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:20:20 INFO - 'ts_paint', 18:20:20 INFO - 'tpaint', 18:20:20 INFO - 'sessionrestore', 18:20:20 INFO - 'sessionrestore_no_auto_restore', 18:20:20 INFO - 'tabpaint']}, 18:20:20 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:20:20 INFO - 'ts_paint', 18:20:20 INFO - 'tpaint', 18:20:20 INFO - 'sessionrestore', 18:20:20 INFO - 'sessionrestore_no_auto_restore', 18:20:20 INFO - 'tabpaint']}, 18:20:20 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['a11yr', 18:20:20 INFO - 'ts_paint', 18:20:20 INFO - 'tpaint', 18:20:20 INFO - 'sessionrestore', 18:20:20 INFO - 'sessionrestore_no_auto_restore', 18:20:20 INFO - 'tabpaint']}, 18:20:20 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['a11yr', 18:20:20 INFO - 'ts_paint', 18:20:20 INFO - 'tpaint', 18:20:20 INFO - 'sessionrestore', 18:20:20 INFO - 'sessionrestore_no_auto_restore', 18:20:20 INFO - 'tabpaint']}, 18:20:20 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['tsvgx', 18:20:20 INFO - 'tsvgr_opacity', 18:20:20 INFO - 'tart', 18:20:20 INFO - 'tscrollx', 18:20:20 INFO - 'cart']}, 18:20:20 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:20:20 INFO - 'tsvgr_opacity', 18:20:20 INFO - 'tart', 18:20:20 INFO - 'tscrollx', 18:20:20 INFO - 'cart']}, 18:20:20 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'talos_options': ['--disable-e10s'], 18:20:20 INFO - 'tests': ['tp5o']}, 18:20:20 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'tests': ['tp5o']}, 18:20:20 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'talos_options': ['--disable-e10s', 18:20:20 INFO - '--xperf_path', 18:20:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:20 INFO - 'tests': ['tp5n']}, 18:20:20 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:20:20 INFO - 'talos_options': ['--xperf_path', 18:20:20 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:20:20 INFO - 'tests': ['tp5n']}}, 18:20:20 INFO - 'talos.zip': {'path': '', 18:20:20 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:20:20 INFO - Running post-action listener: _resource_record_post_action 18:20:20 INFO - [mozharness: 2016-10-14 01:20:20.674362Z] Finished populate-webroot step (success) 18:20:20 INFO - [mozharness: 2016-10-14 01:20:20.674443Z] Running create-virtualenv step. 18:20:20 INFO - Running pre-action listener: _resource_record_pre_action 18:20:20 INFO - Running main action method: create_virtualenv 18:20:20 INFO - Creating virtualenv /builds/slave/test/build/venv 18:20:20 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 18:20:20 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:20:20 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:20:20 INFO - Using real prefix '/tools/python27' 18:20:20 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:20:21 INFO - Installing distribute.............................................................................................................................................................................................done. 18:20:24 INFO - Installing pip.................done. 18:20:24 INFO - Return code: 0 18:20:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:20:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:20:24 INFO - Reading from file tmpfile_stdout 18:20:24 INFO - Output received: 18:20:24 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) 18:20:24 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:20:24 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')]} 18:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:24 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')]} 18:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:24 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:24 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 18:20:24 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 18:20:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:24 INFO - 'HOME': '/Users/cltbld', 18:20:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:24 INFO - 'LOGNAME': 'cltbld', 18:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:24 INFO - 'MOZ_NO_REMOTE': '1', 18:20:24 INFO - 'NO_EM_RESTART': '1', 18:20:24 INFO - 'PAGER': '/bin/cat', 18:20:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:24 INFO - 'PWD': '/builds/slave/test', 18:20:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:24 INFO - 'SHELL': '/bin/bash', 18:20:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:24 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:24 INFO - 'USER': 'cltbld', 18:20:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:24 INFO - 'XPC_FLAGS': '0x0', 18:20:24 INFO - 'XPC_SERVICE_NAME': '0', 18:20:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:24 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 18:20:24 INFO - Cleaning up... 18:20:24 INFO - Return code: 0 18:20:24 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:20:24 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')]} 18:20:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:24 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')]} 18:20:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:24 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:24 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 18:20:24 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 18:20:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:24 INFO - 'HOME': '/Users/cltbld', 18:20:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:24 INFO - 'LOGNAME': 'cltbld', 18:20:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:24 INFO - 'MOZ_NO_REMOTE': '1', 18:20:24 INFO - 'NO_EM_RESTART': '1', 18:20:24 INFO - 'PAGER': '/bin/cat', 18:20:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:24 INFO - 'PWD': '/builds/slave/test', 18:20:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:24 INFO - 'SHELL': '/bin/bash', 18:20:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:24 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:24 INFO - 'USER': 'cltbld', 18:20:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:24 INFO - 'XPC_FLAGS': '0x0', 18:20:24 INFO - 'XPC_SERVICE_NAME': '0', 18:20:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:25 INFO - Downloading/unpacking psutil>=3.1.1 18:20:25 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 18:20:25 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 18:20:25 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 18:20:25 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 18:20:25 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 18:20:25 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 18:20:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:20:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:28 INFO - Installing collected packages: psutil 18:20:28 INFO - Running setup.py install for psutil 18:20:28 INFO - building 'psutil._psutil_osx' extension 18:20:28 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 18:20:28 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 18:20:28 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 18:20:29 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 18:20:29 INFO - building 'psutil._psutil_posix' extension 18:20:29 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 18:20:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:20:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:20:29 INFO - ^ 18:20:29 INFO - 1 warning generated. 18:20:29 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 18:20:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:20:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:20:29 INFO - Successfully installed psutil 18:20:29 INFO - Cleaning up... 18:20:29 INFO - Return code: 0 18:20:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:20:29 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')]} 18:20:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:29 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')]} 18:20:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:29 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:29 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 18:20:29 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 18:20:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:29 INFO - 'HOME': '/Users/cltbld', 18:20:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:29 INFO - 'LOGNAME': 'cltbld', 18:20:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:29 INFO - 'MOZ_NO_REMOTE': '1', 18:20:29 INFO - 'NO_EM_RESTART': '1', 18:20:29 INFO - 'PAGER': '/bin/cat', 18:20:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:29 INFO - 'PWD': '/builds/slave/test', 18:20:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:29 INFO - 'SHELL': '/bin/bash', 18:20:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:29 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:29 INFO - 'USER': 'cltbld', 18:20:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:29 INFO - 'XPC_FLAGS': '0x0', 18:20:29 INFO - 'XPC_SERVICE_NAME': '0', 18:20:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:20:29 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 18:20:29 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 18:20:29 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 18:20:29 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 18:20:29 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 18:20:29 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 18:20:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:20:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:20:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:20:32 INFO - Installing collected packages: mozsystemmonitor 18:20:32 INFO - Running setup.py install for mozsystemmonitor 18:20:32 INFO - Successfully installed mozsystemmonitor 18:20:32 INFO - Cleaning up... 18:20:32 INFO - Return code: 0 18:20:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:20: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')]} 18:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20: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')]} 18:20:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:32 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:32 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 18:20:32 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 18:20:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:32 INFO - 'HOME': '/Users/cltbld', 18:20:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:32 INFO - 'LOGNAME': 'cltbld', 18:20:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:32 INFO - 'MOZ_NO_REMOTE': '1', 18:20:32 INFO - 'NO_EM_RESTART': '1', 18:20:32 INFO - 'PAGER': '/bin/cat', 18:20:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:32 INFO - 'PWD': '/builds/slave/test', 18:20:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:32 INFO - 'SHELL': '/bin/bash', 18:20:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:32 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:32 INFO - 'USER': 'cltbld', 18:20:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:32 INFO - 'XPC_FLAGS': '0x0', 18:20:32 INFO - 'XPC_SERVICE_NAME': '0', 18:20:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:32 INFO - Downloading/unpacking jsonschema==2.5.1 18:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:20:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:20:35 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 18:20:35 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 18:20:35 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 18:20:35 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 18:20:35 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 18:20:35 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 18:20:35 INFO - Downloading functools32-3.2.3-2.tar.gz 18:20:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:20:35 INFO - warning: no files found matching '*.txt' 18:20:35 INFO - no previously-included directories found matching 'build' 18:20:35 INFO - no previously-included directories found matching 'dist' 18:20:35 INFO - no previously-included directories found matching '.git*' 18:20:35 INFO - Installing collected packages: jsonschema, functools32 18:20:35 INFO - Running setup.py install for functools32 18:20:35 INFO - warning: no files found matching '*.txt' 18:20:35 INFO - no previously-included directories found matching 'build' 18:20:35 INFO - no previously-included directories found matching 'dist' 18:20:35 INFO - no previously-included directories found matching '.git*' 18:20:36 INFO - Successfully installed jsonschema functools32 18:20:36 INFO - Cleaning up... 18:20:36 INFO - Return code: 0 18:20:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:36 INFO - 'HOME': '/Users/cltbld', 18:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:36 INFO - 'LOGNAME': 'cltbld', 18:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:36 INFO - 'MOZ_NO_REMOTE': '1', 18:20:36 INFO - 'NO_EM_RESTART': '1', 18:20:36 INFO - 'PAGER': '/bin/cat', 18:20:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:36 INFO - 'PWD': '/builds/slave/test', 18:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:36 INFO - 'SHELL': '/bin/bash', 18:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:36 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:36 INFO - 'USER': 'cltbld', 18:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:36 INFO - 'XPC_FLAGS': '0x0', 18:20:36 INFO - 'XPC_SERVICE_NAME': '0', 18:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:20:36 INFO - Cleaning up... 18:20:36 INFO - Return code: 0 18:20:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:20:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:20:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:36 INFO - 'HOME': '/Users/cltbld', 18:20:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:36 INFO - 'LOGNAME': 'cltbld', 18:20:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:36 INFO - 'MOZ_NO_REMOTE': '1', 18:20:36 INFO - 'NO_EM_RESTART': '1', 18:20:36 INFO - 'PAGER': '/bin/cat', 18:20:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:36 INFO - 'PWD': '/builds/slave/test', 18:20:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:36 INFO - 'SHELL': '/bin/bash', 18:20:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:36 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:36 INFO - 'USER': 'cltbld', 18:20:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:36 INFO - 'XPC_FLAGS': '0x0', 18:20:36 INFO - 'XPC_SERVICE_NAME': '0', 18:20:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:36 INFO - Downloading/unpacking blobuploader==1.2.4 18:20:36 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 18:20:36 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 18:20:36 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 18:20:36 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 18:20:36 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 18:20:36 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 18:20:39 INFO - Downloading blobuploader-1.2.4.tar.gz 18:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:20:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:20: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 18:20: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 18:20: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 18:20: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 18:20: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 18:20: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 18:20:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:20:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:20: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 18:20: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 18:20: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 18:20: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 18:20: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 18:20: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 18:20:40 INFO - Downloading docopt-0.6.1.tar.gz 18:20:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:20:40 INFO - Installing collected packages: blobuploader, requests, docopt 18:20:40 INFO - Running setup.py install for blobuploader 18:20:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:20:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:20:40 INFO - Running setup.py install for requests 18:20:41 INFO - Running setup.py install for docopt 18:20:41 INFO - Successfully installed blobuploader requests docopt 18:20:41 INFO - Cleaning up... 18:20:41 INFO - Return code: 0 18:20:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:41 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')]} 18:20:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:41 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')]} 18:20:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:41 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:41 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 18:20:41 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 18:20:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:41 INFO - 'HOME': '/Users/cltbld', 18:20:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:41 INFO - 'LOGNAME': 'cltbld', 18:20:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:41 INFO - 'MOZ_NO_REMOTE': '1', 18:20:41 INFO - 'NO_EM_RESTART': '1', 18:20:41 INFO - 'PAGER': '/bin/cat', 18:20:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:41 INFO - 'PWD': '/builds/slave/test', 18:20:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:41 INFO - 'SHELL': '/bin/bash', 18:20:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:41 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:41 INFO - 'USER': 'cltbld', 18:20:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:41 INFO - 'XPC_FLAGS': '0x0', 18:20:41 INFO - 'XPC_SERVICE_NAME': '0', 18:20:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:41 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-JmLvDJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:41 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ZOE3pU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:41 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-1Eq7kG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:41 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-S5M8Cs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-J2pSxW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-nLJxVg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-lPRN9H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-9txWjn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-dOSQXb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-VmSP0w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-_ahflN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-renyL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-iwl9Hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:42 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-ktzt3O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:43 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-apzKGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:43 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-uKRTAB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:43 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-Q8VCtd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:20:43 INFO - Running setup.py install for manifestparser 18:20:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:20:43 INFO - Running setup.py install for mozcrash 18:20:43 INFO - Running setup.py install for mozdebug 18:20:43 INFO - Running setup.py install for mozdevice 18:20:43 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:20:43 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:20:43 INFO - Running setup.py install for mozfile 18:20:44 INFO - Running setup.py install for mozhttpd 18:20:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Running setup.py install for mozinfo 18:20:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Running setup.py install for mozInstall 18:20:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Running setup.py install for mozleak 18:20:44 INFO - Running setup.py install for mozlog 18:20:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:20:44 INFO - Running setup.py install for moznetwork 18:20:44 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:20:45 INFO - Running setup.py install for mozprocess 18:20:45 INFO - Running setup.py install for mozprofile 18:20:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:20:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:20:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:20:45 INFO - Running setup.py install for mozrunner 18:20:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:20:45 INFO - Running setup.py install for mozscreenshot 18:20:45 INFO - Running setup.py install for moztest 18:20:45 INFO - Running setup.py install for mozversion 18:20:45 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:20:45 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:20:45 INFO - Cleaning up... 18:20:45 INFO - Return code: 0 18:20:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:45 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:45 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 18:20:45 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 18:20:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:45 INFO - 'HOME': '/Users/cltbld', 18:20:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:45 INFO - 'LOGNAME': 'cltbld', 18:20:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:45 INFO - 'MOZ_NO_REMOTE': '1', 18:20:45 INFO - 'NO_EM_RESTART': '1', 18:20:45 INFO - 'PAGER': '/bin/cat', 18:20:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:45 INFO - 'PWD': '/builds/slave/test', 18:20:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:45 INFO - 'SHELL': '/bin/bash', 18:20:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:45 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:45 INFO - 'USER': 'cltbld', 18:20:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:45 INFO - 'XPC_FLAGS': '0x0', 18:20:45 INFO - 'XPC_SERVICE_NAME': '0', 18:20:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-gMP_YP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-EDQP2l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XSxRJq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-tGOOUA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-6FIE6f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-hkD442-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-npmteD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:20:46 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)) 18:20:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:20:46 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-XqY36K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-CAQsso-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-wc8uIU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-P3IIHC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-P234O5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-dXdjJ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-luca44-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-_G7HUm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-7ydEtn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:20:47 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)) 18:20:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:20:47 INFO - Running setup.py (path:/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/pip-kMcfMJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:20:48 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)) 18:20:48 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)) 18:20:48 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)) 18:20:48 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)) 18:20:48 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)) 18:20:48 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)) 18:20:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:20:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:20:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:20:50 INFO - Downloading blessings-1.6.tar.gz 18:20:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:20:50 INFO - Installing collected packages: blessings 18:20:50 INFO - Running setup.py install for blessings 18:20:50 INFO - Successfully installed blessings 18:20:50 INFO - Cleaning up... 18:20:50 INFO - Return code: 0 18:20:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:20:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:51 INFO - Reading from file tmpfile_stdout 18:20:51 INFO - Current package versions: 18:20:51 INFO - blessings == 1.6 18:20:51 INFO - blobuploader == 1.2.4 18:20:51 INFO - docopt == 0.6.1 18:20:51 INFO - functools32 == 3.2.3-2 18:20:51 INFO - jsonschema == 2.5.1 18:20:51 INFO - manifestparser == 1.1 18:20:51 INFO - mozInstall == 1.12 18:20:51 INFO - mozcrash == 0.17 18:20:51 INFO - mozdebug == 0.1 18:20:51 INFO - mozdevice == 0.48 18:20:51 INFO - mozfile == 1.2 18:20:51 INFO - mozhttpd == 0.7 18:20:51 INFO - mozinfo == 0.9 18:20:51 INFO - mozleak == 0.1 18:20:51 INFO - mozlog == 3.4 18:20:51 INFO - moznetwork == 0.27 18:20:51 INFO - mozprocess == 0.23 18:20:51 INFO - mozprofile == 0.28 18:20:51 INFO - mozrunner == 6.12 18:20:51 INFO - mozscreenshot == 0.1 18:20:51 INFO - mozsystemmonitor == 0.3 18:20:51 INFO - moztest == 0.7 18:20:51 INFO - mozversion == 1.4 18:20:51 INFO - psutil == 3.1.1 18:20:51 INFO - requests == 1.2.3 18:20:51 INFO - wsgiref == 0.1.2 18:20:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:51 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 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:51 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 18:20:51 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 18:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:51 INFO - 'HOME': '/Users/cltbld', 18:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:51 INFO - 'LOGNAME': 'cltbld', 18:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:51 INFO - 'MOZ_NO_REMOTE': '1', 18:20:51 INFO - 'NO_EM_RESTART': '1', 18:20:51 INFO - 'PAGER': '/bin/cat', 18:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:51 INFO - 'PWD': '/builds/slave/test', 18:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:51 INFO - 'SHELL': '/bin/bash', 18:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:51 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:51 INFO - 'USER': 'cltbld', 18:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:51 INFO - 'XPC_FLAGS': '0x0', 18:20:51 INFO - 'XPC_SERVICE_NAME': '0', 18:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 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)) 18:20:51 INFO - Cleaning up... 18:20:51 INFO - Return code: 0 18:20:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:20:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:20:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:20:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:20:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ed72490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ece8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff98346afc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, '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.POHXzahlSD/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.6eAwUATTHC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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 18:20:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:20:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:20:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:20:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:20:51 INFO - 'HOME': '/Users/cltbld', 18:20:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:20:51 INFO - 'LOGNAME': 'cltbld', 18:20:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:20:51 INFO - 'MOZ_NO_REMOTE': '1', 18:20:51 INFO - 'NO_EM_RESTART': '1', 18:20:51 INFO - 'PAGER': '/bin/cat', 18:20:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:20:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:20:51 INFO - 'PWD': '/builds/slave/test', 18:20:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:20:51 INFO - 'SHELL': '/bin/bash', 18:20:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:20:51 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:20:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:20:51 INFO - 'USER': 'cltbld', 18:20:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:20:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:20:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:20:51 INFO - 'XPC_FLAGS': '0x0', 18:20:51 INFO - 'XPC_SERVICE_NAME': '0', 18:20:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:20:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:20:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:20:51 INFO - Cleaning up... 18:20:51 INFO - Return code: 0 18:20:51 INFO - Running post-action listener: _resource_record_post_action 18:20:51 INFO - Running post-action listener: _start_resource_monitoring 18:20:51 INFO - Starting resource monitoring. 18:20:51 INFO - [mozharness: 2016-10-14 01:20:51.724076Z] Finished create-virtualenv step (success) 18:20:51 INFO - [mozharness: 2016-10-14 01:20:51.725170Z] Running install step. 18:20:51 INFO - Running pre-action listener: _resource_record_pre_action 18:20:51 INFO - Running main action method: install 18:20:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:20:51 INFO - Reading from file tmpfile_stdout 18:20:51 INFO - Detecting whether we're running mozinstall >=1.0... 18:20:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:20:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:20:52 INFO - Reading from file tmpfile_stdout 18:20:52 INFO - Output received: 18:20:52 INFO - Usage: mozinstall [options] installer 18:20:52 INFO - Options: 18:20:52 INFO - -h, --help show this help message and exit 18:20:52 INFO - -d DEST, --destination=DEST 18:20:52 INFO - Directory to install application into. [default: 18:20:52 INFO - "/builds/slave/test"] 18:20:52 INFO - --app=APP Application being installed. [default: firefox] 18:20:52 INFO - mkdir: /builds/slave/test/build/application 18:20:52 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'] 18:20:52 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 18:21:26 INFO - Reading from file tmpfile_stdout 18:21:26 INFO - Output received: 18:21:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:21:26 INFO - Running post-action listener: _resource_record_post_action 18:21:26 INFO - [mozharness: 2016-10-14 01:21:26.087297Z] Finished install step (success) 18:21:26 INFO - [mozharness: 2016-10-14 01:21:26.087455Z] Running run-tests step. 18:21:26 INFO - Running pre-action listener: _resource_record_pre_action 18:21:26 INFO - Running main action method: run_tests 18:21:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:21:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:21:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:21:26 INFO - 2016-10-13 18:21:26.102 screenresolution[2242:24471] starting screenresolution argv=screenresolution get 18:21:26 INFO - 2016-10-13 18:21:26.105 screenresolution[2242:24471] Display 0: 1600x1200x32@60 18:21:26 INFO - 2016-10-13 18:21:26.111 screenresolution[2243:24473] starting screenresolution argv=screenresolution list 18:21:26 INFO - Available Modes on Display 0 18:21:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:21:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:21:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:21:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:21:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:21:26 INFO - Intel Iris: 18:21:26 INFO - Chipset Model: Intel Iris 18:21:26 INFO - Type: GPU 18:21:26 INFO - Bus: Built-In 18:21:26 INFO - VRAM (Dynamic, Max): 1536 MB 18:21:26 INFO - Vendor: Intel (0x8086) 18:21:26 INFO - Device ID: 0x0a2e 18:21:26 INFO - Revision ID: 0x0009 18:21:26 INFO - Displays: 18:21:26 INFO - Display: 18:21:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:21:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:21:26 INFO - Display Serial Number: 1600x1200 60 18:21:26 INFO - Main Display: Yes 18:21:26 INFO - Mirror: Off 18:21:26 INFO - Online: Yes 18:21:26 INFO - Rotation: Supported 18:21:26 INFO - Return code: 0 18:21:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:21:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:21:26 INFO - Python 2.7.3 18:21:26 INFO - Return code: 0 18:21:26 INFO - grabbing minidump binary from tooltool 18:21:26 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')]} 18:21:26 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 0x10eb86d30>, '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 0x10e9acdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eb7eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:21:26 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 18:21:26 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 18:21:26 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 18:21:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:21:26 INFO - Return code: 0 18:21:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:21:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:21:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:21:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:21:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:21:26 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/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 18:21:26 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/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0330 --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 18:21:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.POHXzahlSD/Render', 18:21:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:21:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:21:26 INFO - 'HOME': '/Users/cltbld', 18:21:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:21:26 INFO - 'LOGNAME': 'cltbld', 18:21:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:21:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:21:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:21:26 INFO - 'MOZ_NO_REMOTE': '1', 18:21:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:21:26 INFO - 'NO_EM_RESTART': '1', 18:21:26 INFO - 'PAGER': '/bin/cat', 18:21:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:21:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:21:26 INFO - 'PWD': '/builds/slave/test', 18:21:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:21:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:21:26 INFO - 'SHELL': '/bin/bash', 18:21:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6eAwUATTHC/Listeners', 18:21:26 INFO - 'TMPDIR': '/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/', 18:21:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:21:26 INFO - 'USER': 'cltbld', 18:21:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:21:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:21:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:21:26 INFO - 'XPC_FLAGS': '0x0', 18:21:26 INFO - 'XPC_SERVICE_NAME': '0', 18:21:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:21:26 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/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0330', '--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 18:21:26 INFO - mozversion application_buildid: 20161013162335 18:21:26 INFO - mozversion application_changeset: 96f862cb3d91729aeaef135b9a9c62d726d47053 18:21:26 INFO - mozversion application_display_name: Nightly 18:21:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:21:26 INFO - mozversion application_name: Firefox 18:21:26 INFO - mozversion application_remotingname: firefox 18:21:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 18:21:26 INFO - mozversion application_vendor: Mozilla 18:21:26 INFO - mozversion application_version: 52.0a1 18:21:26 INFO - mozversion platform_buildid: 20161013162335 18:21:26 INFO - mozversion platform_changeset: 96f862cb3d91729aeaef135b9a9c62d726d47053 18:21:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 18:21:26 INFO - mozversion platform_version: 52.0a1 18:21:26 INFO - using testdate: 1476408086 18:21:26 INFO - actual date: 1476408086 18:21:26 INFO - starting webserver on 'localhost:58220' 18:21:26 INFO - SUITE-START | Running 2 tests 18:21:26 INFO - TEST-START | dromaeo_css 18:21:26 INFO - operating with platform_type : mac_ 18:21:26 INFO - Initialising browser for dromaeo_css test... 18:21:26 INFO - TEST-INFO | started process 2253 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpJ7V7HV/profile http://localhost:58220/getInfo.html) 18:21:29 INFO - PROCESS | 2253 | 2016-10-13 18:21:29.450 plugin-container[2255:24632] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x903f, name = 'com.apple.tsm.portname' 18:21:29 INFO - PROCESS | 2253 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:29 INFO - PROCESS | 2253 | 2016-10-13 18:21:29.454 plugin-container[2255:24632] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 18:21:29 INFO - PROCESS | 2253 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:29 INFO - PROCESS | 2253 | 2016-10-13 18:21:29.454 plugin-container[2255:24632] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:29 INFO - PROCESS | 2253 | __metrics Screen width/height:1600/1200 18:21:29 INFO - PROCESS | 2253 | colorDepth:24 18:21:29 INFO - PROCESS | 2253 | Browser inner width/height: 1024/689 18:21:29 INFO - PROCESS | 2253 | __metrics 18:21:29 ERROR - PROCESS | 2253 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 18:21:30 INFO - TEST-INFO | 2253: exit 0 18:21:30 INFO - Browser initialized. 18:21:30 INFO - Running cycle 1/1 for dromaeo_css test... 18:21:30 INFO - TEST-INFO | started process 2256 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpJ7V7HV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:21:32 INFO - PROCESS | 2256 | 2016-10-13 18:21:32.910 plugin-container[2257:24750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 18:21:32 INFO - PROCESS | 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:32 INFO - PROCESS | 2256 | 2016-10-13 18:21:32.911 plugin-container[2257:24750] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 18:21:32 INFO - PROCESS | 2256 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:21:32 INFO - PROCESS | 2256 | 2016-10-13 18:21:32.911 plugin-container[2257:24750] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:21:42 INFO - PROCESS | 2256 | RSS: Main: 238964736 18:21:42 INFO - PROCESS | 2256 | 18:22:32 INFO - PROCESS | 2256 | 1476408152217 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] 18:24:44 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58220/tests/dromaeo/cssquery-dojo.html (next: http://localhost:58220/tests/dromaeo/cssquery-ext.html) 18:24:46 INFO - PROCESS | 2256 | RSS: Main: 254914560 18:24:46 INFO - PROCESS | 2256 | 18:27:48 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58220/tests/dromaeo/cssquery-ext.html (next: http://localhost:58220/tests/dromaeo/cssquery-jquery.html) 18:27:49 INFO - PROCESS | 2256 | RSS: Main: 265682944 18:27:49 INFO - PROCESS | 2256 | 18:30:51 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58220/tests/dromaeo/cssquery-jquery.html (next: http://localhost:58220/tests/dromaeo/cssquery-mootools.html) 18:30:51 INFO - PROCESS | 2256 | RSS: Main: 269111296 18:30:51 INFO - PROCESS | 2256 | 18:33:53 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58220/tests/dromaeo/cssquery-mootools.html (next: http://localhost:58220/tests/dromaeo/cssquery-prototype.html) 18:33:54 INFO - PROCESS | 2256 | RSS: Main: 268963840 18:33:54 INFO - PROCESS | 2256 | 18:36:57 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58220/tests/dromaeo/cssquery-prototype.html (next: http://localhost:58220/tests/dromaeo/cssquery-yui.html) 18:36:57 INFO - PROCESS | 2256 | RSS: Main: 274423808 18:36:57 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | Cycle 1(1): loaded http://localhost:58220/tests/dromaeo/cssquery-yui.html (next: http://localhost:58220/tests/dromaeo/cssquery-dojo.html) 18:40:00 INFO - PROCESS | 2256 | RSS: Main: 278085632 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | __start_tp_report 18:40:00 INFO - PROCESS | 2256 | _x_x_mozilla_page_load 18:40:00 INFO - PROCESS | 2256 | _x_x_mozilla_page_load_details 18:40:00 INFO - PROCESS | 2256 | |i|pagename|runs| 18:40:00 INFO - PROCESS | 2256 | |0;dojo.html;2694.5823397373415;3241.7568682348824;3258.690424409683;3306.189983454054;3318.9004329870113;4738.028704115656;5458.918116228259;5553;5569.136783798507;5581.553475721943;5559.19391688205;5608.9439105608935;5703.03048481758;5726.513246374063;5779.2775903012125;5069.797208111671;5141.228815677641;5175.8188463403785;5206.505381988724;5242.606804489659;5187.195984622371;5240.764165612538;5295.894082118355;5319.308489896308;5319.574434045287;4341.435613370241;4897.7795999180125;5183.092958732207;5225.111731005719;5299.576033917297;4957.925631115536;5062.164742817431;5109.182530795055;5141.434442211354;5166.302549155854;5055.216441451581;5070.797168113271;5077.213031980049;5077.746112694351;5180.430152683201;5531.170324451325;5642.689652069134;5659.51894089003;5663;5678.659280443146;6093.634381937098;6100.206973093514;6168.75324987;6199.969000154971;6205.81382558524;6127.908081378783;6133;6177.073438984144;6179.84550386244;6195.876082478325;5502.312210973629;5539.002979463735;5781.855453613609;5786.710664466802;5810.128480727883;6129.878419756102;6174.351693072253;6189.473894718999;6226.719797609118;6278.183836101278;6085.391460853968;6131.50947924165;6133.877322453526;6139.539534534928;6157.476614487819;6033.42682445167;6174.876502469925;6194.34959329273;6220.968895155585;6237.594556353823;5981.282246130492;6089.147519347295;6106.97642067191;6124.203853499017;6140.688304616475;6099.420555047263;6132.356102609251;6163.476104531075;6167.352427995085;6197.5351848610635;4867.196912509467;4880.975595122001;4885.828995985124;4895.681780684244;4942.975285123552;4346.956530434655;4347.500037495665;4357.281048627005;4360.324149756792;4385.407969924083;5767.192593036978;5810.012297909366;5830.183774271605;5845.912311315334;5908.084246941731;6144.38556144382;6171.629702217791;6172.753089876354;6179.536534759911;6180;5336.199570064521;5338.839834804962;5806.5645076618575;5843.737031833578;5845.620034697731;5307.52232299095;5335.413104558495;5348.358197016383;5373.516383525502;5417.539509141767;5217.452167522356;5267.473252674702;5458.153986132156;6114.838180745644;6278.183836101461;6101.05433657766;6131.264248290056;6165.352637972948;6188.350223226496;6192.349803270772;6152.753889844356;6162.815115546541;6192.411720886508;6196.132541444292;6305.369463053658;6143.662098584621;6150.569460137747;6156.384361563808;6171.814845554641;6177.444030037318;6051.909221361595;6116.5718399711595;6117.877642447037;6125.111858780363;6139.544182327105;5921.437463440966;5933.050711886078;5943.1679564861815;5947.137665038632;6042.274927008787;5707.7442962548175;5960.195373624679;5970.432808883149;5975;5995.160677505066;4981.352424184833;5645.802396916252;5791.971040144772;5795.072788353843;5844.503217226414;6009.579329446897;6019.759209631566;6043;6092.45167934888;6133.325334213322;5930.347661757117;5946.643201407929;5954.940450595612;5955.047192449188;5955.553333499918 18:40:00 INFO - PROCESS | 2256 | |1;ext.html;455.89970206554557;476.64489954983543;507.14039702703934;532.8649975552073;552.0229194326043;37900.81049594752;40766;40767.79616101923;40894.773156805306;40914.36342546294;40945.79527102368;41497.17005659885;41533.37699934503;41615.16769664605;41659;9385.812283754338;9541.618335266581;9593.232541396706;9601.231901447867;9623.903760962407;9497.287703422237;9529.713488679043;9537;9546.71359859202;9568.617255309779;9554;9565.713028609152;9576.090271424204;9587.424754514752;9597.952010239975;9511.334206605541;9554.092361225672;9564.856527152098;9571.521423928776;9586.568604412818;9424.104710052534;9426.811463770686;9517.904820951771;9520.666776662783;9520.95239523805;39287.4106888394;39348.40977385313;39441.605583944365;39516.81449556518;39559;3216.7748257621975;3405.387030334539;3634.909127271813;3798.2783271178387;3904.1801221743467;23954.520909581886;25854.224373268833;30473.781048758025;31237.8438107808;31967;3181.586393768818;3199.3441344524317;3211.7912335698106;3301.7028467438045;3337.149026998124;486234;492287;492721;493124;493745;45814;58857;58896.41103588995;58899.41100588939;59038.40961590329;62923.6853815728;63138.0529292061;63159.94720422355;63306.683466583294;63416.36583634197;15890.52328430149;15897.920510397375;15944.920275398548;16043.197840107949;16204.432844850373;12381.690457738629;12489.812652810215;12494.312812795384;12556.246625202517;12560.685982850502;13583.456661733617;16243.807086043233;16520.586985325222;16791.832081679273;16801.747973780402;3187.6334221564352;3267.281198136406;6854.2117656469145;6854.451643868579;6981.755638552626;785.7053604898164;838.8783626374142;925.6019911438011;941.0824446164935;998.9001099889953;21606.351809445743;27450.176494705192;28007.859960700473;28982.69577869001;31008.68991310058;953.666216824106;960.736631329799;999.8350272205005;1030.057497389895;1081.7836432713489;15569;15807.525774226791;15931.362745490282;16132;16311;633431;638639;642943;647209;647599;58156.41843581595;58712.706436467546;58947.41052589505;59106.40893591095;59396.109058364156;1241.6523373455448;1269.6571925580042;1302.4920281090383;1302.863199364072;1304.4977683591885;23398.883005584863;25425.364365891;26611;30172.245693856952;30944.690553095083;49151.75424122856;51060.744696276284;51072.95708171632;51141;51288;610785;614003;617621;618274;620307;26871.059512916952;26891.596626051018;26912.461750765266;27220.31949201286;27293.181204563894;46668;46948.530514694416;47468.287975679705;47967.52032479631;48213.276800847314;39191.41212881866;39462.80268598639;39497.21005579815;39733.80133099432;40694;38678.033049173995;38895.805520972215;39792.60207397889;40067.1986560273;40139.1972160561;38246.85259442221;38343.808280958416;38361.040973975876;39500.40749388819;39972.40041399323;32373.028809135765;33038.17404564809;34934.475982860786;35033.474497882045;35176.82411587926;25753.742462575134;25784.864671657677;26017.609735854356;26029;26177.73822261753 18:40:00 INFO - PROCESS | 2256 | |2;jquery.html;365.9085228692827;371.9665230129288;377.5639136797;379.98670046548375;397.0649121319293;19261.711074333896;19431.222751089957;19437.70843437348;19909.701354479676;20045.899770501128;16616.335346586122;16809.24358403872;16924.323027078903;19418.611627767437;19963.500912477197;5788.9579875622585;6289.874202515947;6527.902081468794;6819;6850.1779786425695;6617.239017512999;6747.696353664097;6776.390124888759;6779.559328643622;6781.355771201728;5696.401877802833;6429.6142231466265;6703.195616525999;6719.428848547879;6742.2246441659345;6647.734090636393;6692.564983276072;6718.8992165117315;6742.696578653948;7315.597642129681;7414.110306763169;7564.319211270957;7579.658915348822;7597.278258565428;7599.468037237396;23249.070037198493;25637.359066023313;26947.59578606303;28192.718072819214;28424.147275581767;2343.5781559319316;2440.1581454398206;2497.475530138673;2543.224316583441;2902.8983985560403;12260.509579616804;14136.787948180785;16059.919700401424;16121.032738035756;17245.827541724546;1680.529451753511;1926.6146770645787;1942.0775131812418;1952.6192392483472;2088.7806780288156;123377.38311308475;125656;129674;136346.95479567815;139046.33020219722;20697.793022069585;21277.68083478749;21330.466738331666;21379.67930481044;21562.46093847635;33717;34325.65674343224;34393;34453;34485.48271775925;10575.101116405192;10586.57653693859;10803.405812680377;10831.458427078773;10937.234393592531;8774.95612521946;8878.023417424078;8886.24466920319;8960.596773145224;9052.185303322733;7832.825076238496;9512.619495220113;9672.195063431525;9720.416774993522;9781.16860066888;3198.98400507996;3346.263821959165;3594.51474051005;3728.3595218103947;3824.2925058863643;807.3904202327205;824.7402068348451;944.7449188719007;978.9314747967574;1024.073213741565;20090;20765.377038688865;20855.687164692543;20985.895070524854;21753.564928701653;827.9668813247523;829.2619568583965;949.126803340928;949.7768024514234;1017.8167929772712;10496.160307175407;10855.945720271506;10886.40124793144;10917.290376125526;10996.890031099587;500213;586424;589291;598795;613231;90415;115007;117034.80756346975;119619.40190298992;122183;977.237754551451;993.2550587059706;1029.0584115534352;1031.5718976624767;1033.0444338986408;19327.613447731248;19362.8063719361;19643.70534442013;19694.507637309183;19721.901390492956;53938;55179.44820551743;56135.71932140313;56992.86014279608;57308.140377893535;79097.60451197707;80031.59984200042;80976;81323.5933820327;83539.5823020881;6893.586384816927;8008.959955200186;8066.475679080841;8172.8365432689825;8281.047679516932;10555.894441055798;12758.553450629184;13147.342632868127;13204.27376494302;13254.53609123676;9786.951065244913;10961.232713709964;11114.555417783522;11225.71935701614;11403.087752979576;9777.653363192925;10807.459627018461;10813.783724325627;11169.664910052712;11369.658910232707;10369.274150809673;11080.667579972614;11260.09919206478;11467.541298347973;11549.191556590958;7371.373433258019;7406.333429991327;7460.552366858006;7569.826676865095;7677.654505547375;8871.689490867788;9311;10841.403722795007;11070.28043177191;11217.887821121685 18:40:00 INFO - PROCESS | 2256 | |3;mootools.html;1495.5214331413952;1517;1718.87967842251;1806.4670922077985;1850.8611854110945;7982.92017079829;8225.136360682132;8452.830943381128;9027.961784394789;9048.72853814385;8468.703595374163;8758.868616970734;8763.737087887357;8859.645614175426;8879.200871921526;2333.393317737388;2374.857508549484;2377.072941552796;2377.6552399902002;2382.309130352196;2174.6411842046045;2282.338121944634;2371.2293504610984;2378.940526486843;2383.8450500717486;2227.265002549163;2231.843770936035;2282.6461898405773;2352.5059737455153;2366.100881664965;2332.183735692494;2342.8360014798973;2359.1271229645054;2360.752121027293;2364.3498038039506;1823.635272945403;1924.288013435024;1939.854510911673;1996.17158879065;2311.456807650201;7417.369523590502;8884;8911.465312081296;8954.597043133075;8977;5175.249588809639;5349.3045904032615;5353.304070470853;5354.598405119593;5362.0616392131615;3454.7236221102503;3469;3651.269746050774;3651.72612054097;3676.2279921216577;1070.5289672544056;1077.9137668986461;1078.094400703405;1097.6597254851022;1098.181854518381;11686.006689431324;11721.179517433698;11860.347680877549;11901.404929753564;11970.700732481671;4557.470603814914;4627.305904114342;4795.808167673324;4810.148092984226;4820.8794780130775;9655.855162172573;9816.460094694718;9836.754081148028;9877.160441362565;9888.50557472138;5288.497592728685;5308.362996440374;5341;5345.037893179265;5352.00987817255;4370.584794444522;4519.276915693474;4701.341812146302;4814.855554333376;4880;3855.652991230803;3884.4173373994126;3967.6627486663956;4025.1144748155943;4026.3960405938756;1278.8145718870899;1283.794592865137;1288.8002359634272;1295.591888555102;1297.8118172864881;796.7649543384715;873.3275377959063;902.1609902790336;929.6937802387656;938.2447130060367;5655.236543066716;5861.882762344814;5918.556108291809;5990.940090599125;6022.548308876764;811.350919264586;884;894.5437826708295;908.7818923458454;927.9536023198813;3879.8060096995036;3902.23906338267;3914.2562913047;3973.7417067890497;4008.8396464141106;10395.32430392022;10908.563657453771;10912.781744365067;10943.726406839733;10992.505337259843;10828;10831.512581933832;10868.347899126078;10892.183086268562;10896.019358257794;7824.882626760603;8162;8214.8357032859;8255.628496717782;8320.209580089882;5874.530037596896;5924.4667979882015;5948;5983.820485385445;6161;9918.355306905316;10172.796544069226;10215;10221.9488902555;10266.178705703376;10060.39637621745;10175.949120254352;10268.383896966207;10335.793284134426;10404.58381664725;6949.200841903245;7175.282471752992;7396.630168491662;7551.207123252199;7653.387728981779;8400.957995209985;8538.70114545988;8561.1866872647;8563.529005904486;8586.141385861363;6380.393862583047;7374.815629609303;7482.812929676802;7547.320741133324;7623.504472209511;7292.161401438902;7319;7370.889436658347;7388.076490438695;7539.8492030157995;6550.80347589554;6563.507736919845;6650.501212409185;6651.268360480247;6672.966635166793;4942.629302802232;5016.54851063406;5028.622853285945;5036.697798132123;5101.387833460009;4889.46215916256;4970.465062817917;5067.366579177603;5196.116660167856;5209.114450543341 18:40:00 INFO - PROCESS | 2256 | |4;prototype.html;272.9017553680675;273.82064747590323;300.23889440268914;306.7729879888883;315.42435056022765;4762.8571142865685;4762.880927976802;4831.710097394159;5157.79368825247;5542.695151766651;4939.925901111486;5067.049186063365;5461.399246082938;5537.889242215153;5578.30271216098;4047.332190188623;4129.690273229505;4136.503619565657;4192.2663533881605;4209.663226941837;4194.203101410722;4199.853005144821;4219.620234178923;4237.724547904391;4243.702940794145;3427.2974040321674;3832.386818109103;3866;4162.666986641077;4180.372944058385;4024.3359845625437;4133.91732165355;4163.708540402173;4224.61978421942;4240.300350442173;3320.269540701042;3330.2673411849355;3636.1455058061333;3640.908977275589;3643.890683279506;5368.644597126796;5397.946020539784;5463.31708536433;5464.672119672832;5470.671759694431;4630.8147674093;4729.385179926588;4818.325434439182;4829.806807727687;4848.490908454597;4965.826196083156;5123.359580052494;5613.950907918621;5744.626599271034;5800.593958422912;3353.815540145291;3475.322312149132;3527.4356103023392;4002.698448392186;4035.3341698619693;4546.318052292149;5735.799247026334;5891.941080589225;5946.464818166386;6085.9087113692885;3549.8402571884326;3592.1199306170156;4068.805987299955;4091.2635725569685;4118.691098167649;3650.3429382710838;4161.563035881189;4166;4169.60388763067;4169.770662613585;3862.517185351831;4723.866272094741;4783.4499032611;4814.290926721033;4826.7827947742435;4938.987507560942;5419.945800542023;5638.633488823213;5894.351621321651;5926.429496183515;1363.6454521824396;1390.3951780975308;1393.303348325837;1398.7342404943029;1404.768213244803;762.801671565397;789.0767801672057;827.6234313387395;843.4854738609444;845.5307304446092;4582.754450319319;4817.855464336006;5431.121505602037;5650.191938579697;5659.886802263932;5326.493983071602;5403.648762830403;5408.567314614821;5463.31708536433;6183.628982261079;4869.756512174377;4884.780184891689;5236.188390799432;5533.142362933751;5592.888142237214;2749.986250068737;2999.505081661501;3145.811251324928;3193.5209718542405;3199.3441344524317;5904.3210030847;6184.721687523982;6296.433321001132;6324.747010119544;6374.840628984312;5326.760295786699;5460.31746031746;6157.353477884758;6171.747312231003;6182.6599537024995;5353.625246232727;5962.016267315845;6266.905996410058;6268.247810262797;6273.905891411632;5306.389765176899;5807.883842323045;6000.369961154191;6027.246594175728;6038.5169186465855;4898.118338699069;5064.898067302029;5450.563954883679;6016.488598469179;6039.6678182698615;4950.6039516837845;5454;6331.353847999461;6343.429091381798;6361.61830290184;3438.2951494943977;3741.2704522618915;3890;3964.623360780721;3970.861019864291;5689.54483641316;5897.351291357862;5909.331628013821;5910.202122713551;5935.299438755443;4662.347271381941;4983.352164218629;5104.821331253388;5780.566457515619;5867.530597552271;4676.018036212297;4915.21356582945;5826.592138550261;5847.7368518417625;5985.341612422717;5192.532672059533;5803.47768700819;5811.157382179645;5835;5910.970445147746;4972.378452693413;5477.014137455296;5719.342275638355;5860.355360910382;5887.676177810262;5200.985807767449;5801.3908539602735;5818.359980402068;5835.7373918174635;5912.911306330322 18:40:00 INFO - PROCESS | 2256 | |5;yui.html;670.158950517101;678.684411748537;688.828990715783;719.8812195987663;769.8152443413583;13993.650158746037;14684;14687.632809179775;14692.338844751985;14714.337854796546;13278.203307801514;13372.19766813992;13375.799363009563;13389.188270463215;13395.397207125678;653.2455014458309;679.813051410861;808.1958451340895;815.8245977114934;839.95380254086;822.5106061893164;828.8342331533687;843.28320927212;846.6571038729294;846.7967687754925;915.2540679346312;919.4207649181006;920.6685593186481;922.0502882031485;922.7508572685371;896.3725392225435;901.3375169250595;904.4889637354873;904.8597467392598;905.3662436294554;642.408983734965;681.7647911470535;682.4881338995754;683.1494788987692;683.8666460040295;14415.279236038261;14461.78307325391;14493.202873841934;14676.779848302283;14683.48607798722;14222.288885555681;14222.359993800304;14256.643583910381;14368.497102601359;14401.135931844017;11716.53133874644;11756.706082347924;11784;11881.524739010429;12223.877761222362;3712.6101759315425;3773.754705944105;3777.6788972937334;3898.629630185128;3913.119548101683;112175;113719.43140284246;114078;114553.42723286331;117753.41123294328;9094.363394562317;9142.954285228532;9283.303752218611;10212.234082443847;10623.256372054037;20621.484462888548;20990.475238118863;21019.369418917457;21030.369088927357;21066.47333816667;6578.539502234893;7400.5189662671755;7588.696452141852;7635.121960974448;7736;3321.4021476134044;3772.358699021173;3850.9229815403537;4254.127903779718;4272.679549033773;3419.042668052949;3560.64393560647;3564.7682900611376;3566.144125409883;3566.6789988901123;652.7193306877994;667.8196886840623;677.5155763628931;681.8534015186659;682.1166589266911;512.9589632829365;520.5003196930913;606.4663096475074;607.7538596868276;624.9781257656051;7851.528908265523;9187.586558604879;9389.248860091177;9409.341346105706;9551.61793528265;603.1856993059334;625.383996763188;627.3412916437723;627.9937200627935;643.3470027921663;3394.4399174136483;4431.578999994996;4459.9331010034875;4559.6808223424705;4671.002027830257;118958.8104118965;128026.35986820006;133327;157284;157628;132576;133941;136585;137040;139300.1944737468;85426;109002.45498772615;110636;111278;120263.99999999825;7099.929000709927;8538.743837684879;8890.244329232088;8946;9082.500462474627;61573.07640385487;63490.04764928622;65764.67117664381;66927.3307266921;67613.66193169003;78546.21453785388;80978.59510702644;86609.16840727278;87145.56427217824;88791.55604221938;12574.559890403792;12686.936565317113;12712.6821829455;12758.362081895682;13294.933525332312;14185.858141418454;14215.64460888486;14385.784213237019;14796;14972.850271497582;13214.405351759386;13385.775743889937;13472.932635336761;13556.525521606696;13816.723665526431;11473.254238474472;12947.676308091995;13995.790063148857;13995.790063149265;13997.300134993413;11840;12933.54732584355;13371.598852034454;13406.93296533511;13416.664583385493;10817.134629229486;11116.44417779124;11433.542658293907;11541.942290288494;11583.015443687382;9137.680181193626;10063.081706305382;10227.515090792733;11341.432928353384;11382.203245772716 18:40:00 INFO - PROCESS | 2256 | __end_tp_report 18:40:00 INFO - PROCESS | 2256 | __start_cc_report 18:40:00 INFO - PROCESS | 2256 | _x_x_mozilla_cycle_collect,377 18:40:00 INFO - PROCESS | 2256 | __end_cc_report 18:40:00 INFO - PROCESS | 2256 | __startTimestamp1476409200924__endTimestamp 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | ------- Summary: start ------- 18:40:00 INFO - PROCESS | 2256 | Number of tests: 6 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | [#0] dojo.html Cycles:165 Average:5643.83 Median:5845.91 stddev:659.57 (11.3%) stddev-sans-first:619.68 18:40:00 INFO - PROCESS | 2256 | Values: 2694.6 3241.8 3258.7 3306.2 3318.9 4738.0 5458.9 5553.0 5569.1 5581.6 5559.2 5608.9 5703.0 5726.5 5779.3 5069.8 5141.2 5175.8 5206.5 5242.6 5187.2 5240.8 5295.9 5319.3 5319.6 4341.4 4897.8 5183.1 5225.1 5299.6 4957.9 5062.2 5109.2 5141.4 5166.3 5055.2 5070.8 5077.2 5077.7 5180.4 5531.2 5642.7 5659.5 5663.0 5678.7 6093.6 6100.2 6168.8 6200.0 6205.8 6127.9 6133.0 6177.1 6179.8 6195.9 5502.3 5539.0 5781.9 5786.7 5810.1 6129.9 6174.4 6189.5 6226.7 6278.2 6085.4 6131.5 6133.9 6139.5 6157.5 6033.4 6174.9 6194.3 6221.0 6237.6 5981.3 6089.1 6107.0 6124.2 6140.7 6099.4 6132.4 6163.5 6167.4 6197.5 4867.2 4881.0 4885.8 4895.7 4943.0 4347.0 4347.5 4357.3 4360.3 4385.4 5767.2 5810.0 5830.2 5845.9 5908.1 6144.4 6171.6 6172.8 6179.5 6180.0 5336.2 5338.8 5806.6 5843.7 5845.6 5307.5 5335.4 5348.4 5373.5 5417.5 5217.5 5267.5 5458.2 6114.8 6278.2 6101.1 6131.3 6165.4 6188.4 6192.3 6152.8 6162.8 6192.4 6196.1 6305.4 6143.7 6150.6 6156.4 6171.8 6177.4 6051.9 6116.6 6117.9 6125.1 6139.5 5921.4 5933.1 5943.2 5947.1 6042.3 5707.7 5960.2 5970.4 5975.0 5995.2 4981.4 5645.8 5792.0 5795.1 5844.5 6009.6 6019.8 6043.0 6092.5 6133.3 5930.3 5946.6 5954.9 5955.0 5955.6 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | [#1] ext.html Cycles:180 Average:71116.43 Median:40766.90 stddev:157039.02 (385.2%) stddev-sans-first:157389.94 18:40:00 INFO - PROCESS | 2256 | Values: 455.9 476.6 507.1 532.9 552.0 37900.8 40766.0 40767.8 40894.8 40914.4 40945.8 41497.2 41533.4 41615.2 41659.0 9385.8 9541.6 9593.2 9601.2 9623.9 9497.3 9529.7 9537.0 9546.7 9568.6 9554.0 9565.7 9576.1 9587.4 9598.0 9511.3 9554.1 9564.9 9571.5 9586.6 9424.1 9426.8 9517.9 9520.7 9521.0 39287.4 39348.4 39441.6 39516.8 39559.0 3216.8 3405.4 3634.9 3798.3 3904.2 23954.5 25854.2 30473.8 31237.8 31967.0 3181.6 3199.3 3211.8 3301.7 3337.1 486234.0 492287.0 492721.0 493124.0 493745.0 45814.0 58857.0 58896.4 58899.4 59038.4 62923.7 63138.1 63159.9 63306.7 63416.4 15890.5 15897.9 15944.9 16043.2 16204.4 12381.7 12489.8 12494.3 12556.2 12560.7 13583.5 16243.8 16520.6 16791.8 16801.7 3187.6 3267.3 6854.2 6854.5 6981.8 785.7 838.9 925.6 941.1 998.9 21606.4 27450.2 28007.9 28982.7 31008.7 953.7 960.7 999.8 1030.1 1081.8 15569.0 15807.5 15931.4 16132.0 16311.0 633431.0 638639.0 642943.0 647209.0 647599.0 58156.4 58712.7 58947.4 59106.4 59396.1 1241.7 1269.7 1302.5 1302.9 1304.5 23398.9 25425.4 26611.0 30172.2 30944.7 49151.8 51060.7 51073.0 51141.0 51288.0 610785.0 614003.0 617621.0 618274.0 620307.0 26871.1 26891.6 26912.5 27220.3 27293.2 46668.0 46948.5 47468.3 47967.5 48213.3 39191.4 39462.8 39497.2 39733.8 40694.0 38678.0 38895.8 39792.6 40067.2 40139.2 38246.9 38343.8 38361.0 39500.4 39972.4 32373.0 33038.2 34934.5 35033.5 35176.8 25753.7 25784.9 26017.6 26029.0 26177.7 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | [#2] jquery.html Cycles:180 Average:35818.17 Median:3272.62 stddev:96620.55 (2952.4%) stddev-sans-first:96854.93 18:40:00 INFO - PROCESS | 2256 | Values: 365.9 372.0 377.6 380.0 397.1 19261.7 19431.2 19437.7 19909.7 20045.9 16616.3 16809.2 16924.3 19418.6 19963.5 5789.0 6289.9 6527.9 6819.0 6850.2 6617.2 6747.7 6776.4 6779.6 6781.4 5696.4 6429.6 6703.2 6719.4 6742.2 6647.7 6692.6 6718.9 6742.7 7315.6 7414.1 7564.3 7579.7 7597.3 7599.5 23249.1 25637.4 26947.6 28192.7 28424.1 2343.6 2440.2 2497.5 2543.2 2902.9 12260.5 14136.8 16059.9 16121.0 17245.8 1680.5 1926.6 1942.1 1952.6 2088.8 123377.4 125656.0 129674.0 136347.0 139046.3 20697.8 21277.7 21330.5 21379.7 21562.5 33717.0 34325.7 34393.0 34453.0 34485.5 10575.1 10586.6 10803.4 10831.5 10937.2 8775.0 8878.0 8886.2 8960.6 9052.2 7832.8 9512.6 9672.2 9720.4 9781.2 3199.0 3346.3 3594.5 3728.4 3824.3 807.4 824.7 944.7 978.9 1024.1 20090.0 20765.4 20855.7 20985.9 21753.6 828.0 829.3 949.1 949.8 1017.8 10496.2 10855.9 10886.4 10917.3 10996.9 500213.0 586424.0 589291.0 598795.0 613231.0 90415.0 115007.0 117034.8 119619.4 122183.0 977.2 993.3 1029.1 1031.6 1033.0 19327.6 19362.8 19643.7 19694.5 19721.9 53938.0 55179.4 56135.7 56992.9 57308.1 79097.6 80031.6 80976.0 81323.6 83539.6 6893.6 8009.0 8066.5 8172.8 8281.0 10555.9 12758.6 13147.3 13204.3 13254.5 9787.0 10961.2 11114.6 11225.7 11403.1 9777.7 10807.5 10813.8 11169.7 11369.7 10369.3 11080.7 11260.1 11467.5 11549.2 7371.4 7406.3 7460.6 7569.8 7677.7 8871.7 9311.0 10841.4 11070.3 11217.9 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | [#3] mootools.html Cycles:180 Average:5710.77 Median:4956.55 stddev:3233.79 (65.2%) stddev-sans-first:3227.34 18:40:00 INFO - PROCESS | 2256 | Values: 1495.5 1517.0 1718.9 1806.5 1850.9 7982.9 8225.1 8452.8 9028.0 9048.7 8468.7 8758.9 8763.7 8859.6 8879.2 2333.4 2374.9 2377.1 2377.7 2382.3 2174.6 2282.3 2371.2 2378.9 2383.8 2227.3 2231.8 2282.6 2352.5 2366.1 2332.2 2342.8 2359.1 2360.8 2364.3 1823.6 1924.3 1939.9 1996.2 2311.5 7417.4 8884.0 8911.5 8954.6 8977.0 5175.2 5349.3 5353.3 5354.6 5362.1 3454.7 3469.0 3651.3 3651.7 3676.2 1070.5 1077.9 1078.1 1097.7 1098.2 11686.0 11721.2 11860.3 11901.4 11970.7 4557.5 4627.3 4795.8 4810.1 4820.9 9655.9 9816.5 9836.8 9877.2 9888.5 5288.5 5308.4 5341.0 5345.0 5352.0 4370.6 4519.3 4701.3 4814.9 4880.0 3855.7 3884.4 3967.7 4025.1 4026.4 1278.8 1283.8 1288.8 1295.6 1297.8 796.8 873.3 902.2 929.7 938.2 5655.2 5861.9 5918.6 5990.9 6022.5 811.4 884.0 894.5 908.8 928.0 3879.8 3902.2 3914.3 3973.7 4008.8 10395.3 10908.6 10912.8 10943.7 10992.5 10828.0 10831.5 10868.3 10892.2 10896.0 7824.9 8162.0 8214.8 8255.6 8320.2 5874.5 5924.5 5948.0 5983.8 6161.0 9918.4 10172.8 10215.0 10221.9 10266.2 10060.4 10175.9 10268.4 10335.8 10404.6 6949.2 7175.3 7396.6 7551.2 7653.4 8401.0 8538.7 8561.2 8563.5 8586.1 6380.4 7374.8 7482.8 7547.3 7623.5 7292.2 7319.0 7370.9 7388.1 7539.8 6550.8 6563.5 6650.5 6651.3 6673.0 4942.6 5016.5 5028.6 5036.7 5101.4 4889.5 4970.5 5067.4 5196.1 5209.1 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | [#4] prototype.html Cycles:180 Average:4656.53 Median:5114.09 stddev:1453.12 (28.4%) stddev-sans-first:1419.46 18:40:00 INFO - PROCESS | 2256 | Values: 272.9 273.8 300.2 306.8 315.4 4762.9 4762.9 4831.7 5157.8 5542.7 4939.9 5067.0 5461.4 5537.9 5578.3 4047.3 4129.7 4136.5 4192.3 4209.7 4194.2 4199.9 4219.6 4237.7 4243.7 3427.3 3832.4 3866.0 4162.7 4180.4 4024.3 4133.9 4163.7 4224.6 4240.3 3320.3 3330.3 3636.1 3640.9 3643.9 5368.6 5397.9 5463.3 5464.7 5470.7 4630.8 4729.4 4818.3 4829.8 4848.5 4965.8 5123.4 5614.0 5744.6 5800.6 3353.8 3475.3 3527.4 4002.7 4035.3 4546.3 5735.8 5891.9 5946.5 6085.9 3549.8 3592.1 4068.8 4091.3 4118.7 3650.3 4161.6 4166.0 4169.6 4169.8 3862.5 4723.9 4783.4 4814.3 4826.8 4939.0 5419.9 5638.6 5894.4 5926.4 1363.6 1390.4 1393.3 1398.7 1404.8 762.8 789.1 827.6 843.5 845.5 4582.8 4817.9 5431.1 5650.2 5659.9 5326.5 5403.6 5408.6 5463.3 6183.6 4869.8 4884.8 5236.2 5533.1 5592.9 2750.0 2999.5 3145.8 3193.5 3199.3 5904.3 6184.7 6296.4 6324.7 6374.8 5326.8 5460.3 6157.4 6171.7 6182.7 5353.6 5962.0 6266.9 6268.2 6273.9 5306.4 5807.9 6000.4 6027.2 6038.5 4898.1 5064.9 5450.6 6016.5 6039.7 4950.6 5454.0 6331.4 6343.4 6361.6 3438.3 3741.3 3890.0 3964.6 3970.9 5689.5 5897.4 5909.3 5910.2 5935.3 4662.3 4983.4 5104.8 5780.6 5867.5 4676.0 4915.2 5826.6 5847.7 5985.3 5192.5 5803.5 5811.2 5835.0 5911.0 4972.4 5477.0 5719.3 5860.4 5887.7 5201.0 5801.4 5818.4 5835.7 5912.9 18:40:00 INFO - PROCESS | 2256 | 18:40:00 INFO - PROCESS | 2256 | [#5] yui.html Cycles:180 Average:24484.80 Median:3566.41 stddev:39375.20 (1104.1%) stddev-sans-first:39445.06 18:40:00 INFO - PROCESS | 2256 | Values: 670.2 678.7 688.8 719.9 769.8 13993.7 14684.0 14687.6 14692.3 14714.3 13278.2 13372.2 13375.8 13389.2 13395.4 653.2 679.8 808.2 815.8 840.0 822.5 828.8 843.3 846.7 846.8 915.3 919.4 920.7 922.1 922.8 896.4 901.3 904.5 904.9 905.4 642.4 681.8 682.5 683.1 683.9 14415.3 14461.8 14493.2 14676.8 14683.5 14222.3 14222.4 14256.6 14368.5 14401.1 11716.5 11756.7 11784.0 11881.5 12223.9 3712.6 3773.8 3777.7 3898.6 3913.1 112175.0 113719.4 114078.0 114553.4 117753.4 9094.4 9143.0 9283.3 10212.2 10623.3 20621.5 20990.5 21019.4 21030.4 21066.5 6578.5 7400.5 7588.7 7635.1 7736.0 3321.4 3772.4 3850.9 4254.1 4272.7 3419.0 3560.6 3564.8 3566.1 3566.7 652.7 667.8 677.5 681.9 682.1 513.0 520.5 606.5 607.8 625.0 7851.5 9187.6 9389.2 9409.3 9551.6 603.2 625.4 627.3 628.0 643.3 3394.4 4431.6 4459.9 4559.7 4671.0 118958.8 128026.4 133327.0 157284.0 157628.0 132576.0 133941.0 136585.0 137040.0 139300.2 85426.0 109002.5 110636.0 111278.0 120264.0 7099.9 8538.7 8890.2 8946.0 9082.5 61573.1 63490.0 65764.7 66927.3 67613.7 78546.2 80978.6 86609.2 87145.6 88791.6 12574.6 12686.9 12712.7 12758.4 13294.9 14185.9 14215.6 14385.8 14796.0 14972.9 13214.4 13385.8 13472.9 13556.5 13816.7 11473.3 12947.7 13995.8 13995.8 13997.3 11840.0 12933.5 13371.6 13406.9 13416.7 10817.1 11116.4 11433.5 11541.9 11583.0 9137.7 10063.1 10227.5 11341.4 11382.2 18:40:00 INFO - PROCESS | 2256 | -------- Summary: end -------- 18:40:00 INFO - PROCESS | 2256 | 18:40:01 INFO - TEST-INFO | 2256: exit 0 18:40:01 INFO - TEST-OK | dromaeo_css | took 1114862ms 18:40:01 INFO - TEST-START | kraken 18:40:01 INFO - operating with platform_type : mac_ 18:40:01 INFO - Initialising browser for kraken test... 18:40:01 INFO - TEST-INFO | started process 2261 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpQayop1/profile http://localhost:58220/getInfo.html) 18:40:04 INFO - PROCESS | 2261 | 2016-10-13 18:40:04.397 plugin-container[2262:26626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 18:40:04 INFO - PROCESS | 2261 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:04 INFO - PROCESS | 2261 | 2016-10-13 18:40:04.400 plugin-container[2262:26626] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4607, name = 'com.apple.CFPasteboardClient' 18:40:04 INFO - PROCESS | 2261 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:04 INFO - PROCESS | 2261 | 2016-10-13 18:40:04.401 plugin-container[2262:26626] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:40:04 INFO - PROCESS | 2261 | __metrics Screen width/height:1600/1200 18:40:04 INFO - PROCESS | 2261 | colorDepth:24 18:40:04 INFO - PROCESS | 2261 | Browser inner width/height: 1024/689 18:40:04 INFO - PROCESS | 2261 | __metrics 18:40:04 ERROR - PROCESS | 2261 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3083: TypeError: this.tabs is undefined 18:40:05 INFO - TEST-INFO | 2261: exit 0 18:40:05 INFO - Browser initialized. 18:40:05 INFO - Running cycle 1/1 for kraken test... 18:40:05 INFO - TEST-INFO | started process 2263 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/T/tmpQayop1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:40:07 INFO - PROCESS | 2263 | 2016-10-13 18:40:07.655 plugin-container[2264:26741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 18:40:07 INFO - PROCESS | 2263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:07 INFO - PROCESS | 2263 | 2016-10-13 18:40:07.656 plugin-container[2264:26741] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 18:40:07 INFO - PROCESS | 2263 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:40:07 INFO - PROCESS | 2263 | 2016-10-13 18:40:07.656 plugin-container[2264:26741] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:40:17 INFO - PROCESS | 2263 | RSS: Main: 239185920 18:40:17 INFO - PROCESS | 2263 | 18:40:20 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:58220/tests/kraken/driver.html?testName=audio-beat-detection) 18:40:21 INFO - PROCESS | 2263 | RSS: Main: 252841984 18:40:21 INFO - PROCESS | 2263 | 18:40:24 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:58220/tests/kraken/driver.html?testName=audio-dft) 18:40:24 INFO - PROCESS | 2263 | RSS: Main: 260419584 18:40:24 INFO - PROCESS | 2263 | 18:40:28 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:58220/tests/kraken/driver.html?testName=audio-fft) 18:40:28 INFO - PROCESS | 2263 | RSS: Main: 261193728 18:40:28 INFO - PROCESS | 2263 | 18:40:30 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:58220/tests/kraken/driver.html?testName=audio-oscillator) 18:40:31 INFO - PROCESS | 2263 | RSS: Main: 262885376 18:40:31 INFO - PROCESS | 2263 | 18:40:33 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:58220/tests/kraken/driver.html?testName=imaging-gaussian-blur) 18:40:34 INFO - PROCESS | 2263 | RSS: Main: 263884800 18:40:34 INFO - PROCESS | 2263 | 18:40:36 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:58220/tests/kraken/driver.html?testName=imaging-darkroom) 18:40:37 INFO - PROCESS | 2263 | RSS: Main: 264503296 18:40:37 INFO - PROCESS | 2263 | 18:40:40 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:58220/tests/kraken/driver.html?testName=imaging-desaturate) 18:40:41 INFO - PROCESS | 2263 | RSS: Main: 264720384 18:40:41 INFO - PROCESS | 2263 | 18:40:44 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:58220/tests/kraken/driver.html?testName=json-parse-financial) 18:40:44 INFO - PROCESS | 2263 | RSS: Main: 264884224 18:40:44 INFO - PROCESS | 2263 | 18:40:46 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:58220/tests/kraken/driver.html?testName=json-stringify-tinderbox) 18:40:47 INFO - PROCESS | 2263 | RSS: Main: 264445952 18:40:47 INFO - PROCESS | 2263 | 18:40:49 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-aes) 18:40:49 INFO - PROCESS | 2263 | RSS: Main: 264183808 18:40:49 INFO - PROCESS | 2263 | 18:40:51 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-ccm) 18:40:52 INFO - PROCESS | 2263 | RSS: Main: 264237056 18:40:52 INFO - PROCESS | 2263 | 18:40:55 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 18:40:56 INFO - PROCESS | 2263 | RSS: Main: 264556544 18:40:56 INFO - PROCESS | 2263 | 18:40:59 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 18:41:00 INFO - PROCESS | 2263 | RSS: Main: 264327168 18:41:00 INFO - PROCESS | 2263 | 18:41:01 INFO - PROCESS | 2263 | Cycle 1(1): loaded http://localhost:58220/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:58220/tests/kraken/driver.html?testName=ai-astar) 18:41:02 INFO - PROCESS | 2263 | RSS: Main: 266088448 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | __start_tp_report 18:41:02 INFO - PROCESS | 2263 | _x_x_mozilla_page_load 18:41:02 INFO - PROCESS | 2263 | _x_x_mozilla_page_load_details 18:41:02 INFO - PROCESS | 2263 | |i|pagename|runs| 18:41:02 INFO - PROCESS | 2263 | |0;ai-astar;116;118;120;119;118;113;118;119;137;172 18:41:02 INFO - PROCESS | 2263 | |1;audio-beat-detection;156;145;144;171;121;138;149;119;183;148 18:41:02 INFO - PROCESS | 2263 | |2;audio-dft;209;209;199;203;192;197;196;192;201;197 18:41:02 INFO - PROCESS | 2263 | |3;audio-fft;83;60;57;85;61;62;57;64;73;67 18:41:02 INFO - PROCESS | 2263 | |4;audio-oscillator;87;71;93;96;102;103;96;69;70;91 18:41:02 INFO - PROCESS | 2263 | |5;imaging-gaussian-blur;91;80;136;125;89;84;89;93;89;87 18:41:02 INFO - PROCESS | 2263 | |6;imaging-darkroom;95;101;143;150;155;96;95;94;96;94 18:41:02 INFO - PROCESS | 2263 | |7;imaging-desaturate;91;141;149;92;90;90;100;93;91;90 18:41:02 INFO - PROCESS | 2263 | |8;json-parse-financial;58;57;57;63;70;90;91;75;59;59 18:41:02 INFO - PROCESS | 2263 | |9;json-stringify-tinderbox;69;73;74;82;76;70;74;72;76;109 18:41:02 INFO - PROCESS | 2263 | |10;stanford-crypto-aes;106;89;90;92;87;91;88;87;91;103 18:41:02 INFO - PROCESS | 2263 | |11;stanford-crypto-ccm;146;147;146;148;151;145;151;161;203;304 18:41:02 INFO - PROCESS | 2263 | |12;stanford-crypto-pbkdf2;184;202;241;222;191;176;178;210;173;176 18:41:02 INFO - PROCESS | 2263 | |13;stanford-crypto-sha256-iterative;70;70;72;68;71;70;70;67;73;70 18:41:02 INFO - PROCESS | 2263 | __end_tp_report 18:41:02 INFO - PROCESS | 2263 | __start_cc_report 18:41:02 INFO - PROCESS | 2263 | _x_x_mozilla_cycle_collect,958 18:41:02 INFO - PROCESS | 2263 | __end_cc_report 18:41:02 INFO - PROCESS | 2263 | __startTimestamp1476409262154__endTimestamp 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | ------- Summary: start ------- 18:41:02 INFO - PROCESS | 2263 | Number of tests: 14 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#0] ai-astar Cycles:10 Average:125.00 Median:119.00 stddev:17.71 (14.9%) stddev-sans-first:18.48 18:41:02 INFO - PROCESS | 2263 | Values: 116.0 118.0 120.0 119.0 118.0 113.0 118.0 119.0 137.0 172.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#1] audio-beat-detection Cycles:10 Average:147.40 Median:148.50 stddev:19.75 (13.3%) stddev-sans-first:20.70 18:41:02 INFO - PROCESS | 2263 | Values: 156.0 145.0 144.0 171.0 121.0 138.0 149.0 119.0 183.0 148.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#2] audio-dft Cycles:10 Average:199.50 Median:200.00 stddev:6.08 (3.0%) stddev-sans-first:5.39 18:41:02 INFO - PROCESS | 2263 | Values: 209.0 209.0 199.0 203.0 192.0 197.0 196.0 192.0 201.0 197.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#3] audio-fft Cycles:10 Average:66.90 Median:65.50 stddev:10.19 (15.6%) stddev-sans-first:8.99 18:41:02 INFO - PROCESS | 2263 | Values: 83.0 60.0 57.0 85.0 61.0 62.0 57.0 64.0 73.0 67.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#4] audio-oscillator Cycles:10 Average:87.80 Median:89.00 stddev:13.16 (14.8%) stddev-sans-first:13.95 18:41:02 INFO - PROCESS | 2263 | Values: 87.0 71.0 93.0 96.0 102.0 103.0 96.0 69.0 70.0 91.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#5] imaging-gaussian-blur Cycles:10 Average:96.30 Median:89.00 stddev:18.57 (20.9%) stddev-sans-first:19.59 18:41:02 INFO - PROCESS | 2263 | Values: 91.0 80.0 136.0 125.0 89.0 84.0 89.0 93.0 89.0 87.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#6] imaging-darkroom Cycles:10 Average:111.90 Median:94.50 stddev:26.06 (27.6%) stddev-sans-first:26.92 18:41:02 INFO - PROCESS | 2263 | Values: 95.0 101.0 143.0 150.0 155.0 96.0 95.0 94.0 96.0 94.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:90.50 stddev:22.57 (24.9%) stddev-sans-first:23.54 18:41:02 INFO - PROCESS | 2263 | Values: 91.0 141.0 149.0 92.0 90.0 90.0 100.0 93.0 91.0 90.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#8] json-parse-financial Cycles:10 Average:67.90 Median:66.50 stddev:13.31 (20.0%) stddev-sans-first:13.63 18:41:02 INFO - PROCESS | 2263 | Values: 58.0 57.0 57.0 63.0 70.0 90.0 91.0 75.0 59.0 59.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#9] json-stringify-tinderbox Cycles:10 Average:77.50 Median:74.00 stddev:11.65 (15.7%) stddev-sans-first:11.94 18:41:02 INFO - PROCESS | 2263 | Values: 69.0 73.0 74.0 82.0 76.0 70.0 74.0 72.0 76.0 109.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#10] stanford-crypto-aes Cycles:10 Average:92.40 Median:89.50 stddev:6.64 (7.4%) stddev-sans-first:4.88 18:41:02 INFO - PROCESS | 2263 | Values: 106.0 89.0 90.0 92.0 87.0 91.0 88.0 87.0 91.0 103.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#11] stanford-crypto-ccm Cycles:10 Average:170.20 Median:151.00 stddev:50.15 (33.2%) stddev-sans-first:52.42 18:41:02 INFO - PROCESS | 2263 | Values: 146.0 147.0 146.0 148.0 151.0 145.0 151.0 161.0 203.0 304.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.30 Median:196.50 stddev:22.97 (11.7%) stddev-sans-first:24.00 18:41:02 INFO - PROCESS | 2263 | Values: 184.0 202.0 241.0 222.0 191.0 176.0 178.0 210.0 173.0 176.0 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - PROCESS | 2263 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.10 Median:70.00 stddev:1.73 (2.5%) stddev-sans-first:1.83 18:41:02 INFO - PROCESS | 2263 | Values: 70.0 70.0 72.0 68.0 71.0 70.0 70.0 67.0 73.0 70.0 18:41:02 INFO - PROCESS | 2263 | -------- Summary: end -------- 18:41:02 INFO - PROCESS | 2263 | 18:41:02 INFO - TEST-INFO | 2263: exit 0 18:41:03 INFO - TEST-OK | kraken | took 61487ms 18:41:03 INFO - SUITE-END | took 1176s 18:41:03 INFO - Completed test suite (00:19:36) 18:41:03 INFO - javascript benchmark 18:41:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7446.8645621411515, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2694.5823397373415, 3241.7568682348824, 3258.690424409683, 3306.189983454054, 3318.9004329870113, 4738.028704115656, 5458.918116228259, 5553.0, 5569.136783798507, 5581.553475721943, 5559.19391688205, 5608.9439105608935, 5703.03048481758, 5726.513246374063, 5779.2775903012125, 5069.797208111671, 5141.228815677641, 5175.8188463403785, 5206.505381988724, 5242.606804489659, 5187.195984622371, 5240.764165612538, 5295.894082118355, 5319.308489896308, 5319.574434045287, 4341.435613370241, 4897.7795999180125, 5183.092958732207, 5225.111731005719, 5299.576033917297, 4957.925631115536, 5062.164742817431, 5109.182530795055, 5141.434442211354, 5166.302549155854, 5055.216441451581, 5070.797168113271, 5077.213031980049, 5077.746112694351, 5180.430152683201, 5531.170324451325, 5642.689652069134, 5659.51894089003, 5663.0, 5678.659280443146, 6093.634381937098, 6100.206973093514, 6168.75324987, 6199.969000154971, 6205.81382558524, 6127.908081378783, 6133.0, 6177.073438984144, 6179.84550386244, 6195.876082478325, 5502.312210973629, 5539.002979463735, 5781.855453613609, 5786.710664466802, 5810.128480727883, 6129.878419756102, 6174.351693072253, 6189.473894718999, 6226.719797609118, 6278.183836101278, 6085.391460853968, 6131.50947924165, 6133.877322453526, 6139.539534534928, 6157.476614487819, 6033.42682445167, 6174.876502469925, 6194.34959329273, 6220.968895155585, 6237.594556353823, 5981.282246130492, 6089.147519347295, 6106.97642067191, 6124.203853499017, 6140.688304616475, 6099.420555047263, 6132.356102609251, 6163.476104531075, 6167.352427995085, 6197.5351848610635, 4867.196912509467, 4880.975595122001, 4885.828995985124, 4895.681780684244, 4942.975285123552, 4346.956530434655, 4347.500037495665, 4357.281048627005, 4360.324149756792, 4385.407969924083, 5767.192593036978, 5810.012297909366, 5830.183774271605, 5845.912311315334, 5908.084246941731, 6144.38556144382, 6171.629702217791, 6172.753089876354, 6179.536534759911, 6180.0, 5336.199570064521, 5338.839834804962, 5806.5645076618575, 5843.737031833578, 5845.620034697731, 5307.52232299095, 5335.413104558495, 5348.358197016383, 5373.516383525502, 5417.539509141767, 5217.452167522356, 5267.473252674702, 5458.153986132156, 6114.838180745644, 6278.183836101461, 6101.05433657766, 6131.264248290056, 6165.352637972948, 6188.350223226496, 6192.349803270772, 6152.753889844356, 6162.815115546541, 6192.411720886508, 6196.132541444292, 6305.369463053658, 6143.662098584621, 6150.569460137747, 6156.384361563808, 6171.814845554641, 6177.444030037318, 6051.909221361595, 6116.5718399711595, 6117.877642447037, 6125.111858780363, 6139.544182327105, 5921.437463440966, 5933.050711886078, 5943.1679564861815, 5947.137665038632, 6042.274927008787, 5707.7442962548175, 5960.195373624679, 5970.432808883149, 5975.0, 5995.160677505066, 4981.352424184833, 5645.802396916252, 5791.971040144772, 5795.072788353843, 5844.503217226414, 6009.579329446897, 6019.759209631566, 6043.0, 6092.45167934888, 6133.325334213322, 5930.347661757117, 5946.643201407929, 5954.940450595612, 5955.047192449188, 5955.553333499918], "value": 5599.619164910846, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.89970206554557, 476.64489954983543, 507.14039702703934, 532.8649975552073, 552.0229194326043, 37900.81049594752, 40766.0, 40767.79616101923, 40894.773156805306, 40914.36342546294, 40945.79527102368, 41497.17005659885, 41533.37699934503, 41615.16769664605, 41659.0, 9385.812283754338, 9541.618335266581, 9593.232541396706, 9601.231901447867, 9623.903760962407, 9497.287703422237, 9529.713488679043, 9537.0, 9546.71359859202, 9568.617255309779, 9554.0, 9565.713028609152, 9576.090271424204, 9587.424754514752, 9597.952010239975, 9511.334206605541, 9554.092361225672, 9564.856527152098, 9571.521423928776, 9586.568604412818, 9424.104710052534, 9426.811463770686, 9517.904820951771, 9520.666776662783, 9520.95239523805, 39287.4106888394, 39348.40977385313, 39441.605583944365, 39516.81449556518, 39559.0, 3216.7748257621975, 3405.387030334539, 3634.909127271813, 3798.2783271178387, 3904.1801221743467, 23954.520909581886, 25854.224373268833, 30473.781048758025, 31237.8438107808, 31967.0, 3181.586393768818, 3199.3441344524317, 3211.7912335698106, 3301.7028467438045, 3337.149026998124, 486234.0, 492287.0, 492721.0, 493124.0, 493745.0, 45814.0, 58857.0, 58896.41103588995, 58899.41100588939, 59038.40961590329, 62923.6853815728, 63138.0529292061, 63159.94720422355, 63306.683466583294, 63416.36583634197, 15890.52328430149, 15897.920510397375, 15944.920275398548, 16043.197840107949, 16204.432844850373, 12381.690457738629, 12489.812652810215, 12494.312812795384, 12556.246625202517, 12560.685982850502, 13583.456661733617, 16243.807086043233, 16520.586985325222, 16791.832081679273, 16801.747973780402, 3187.6334221564352, 3267.281198136406, 6854.2117656469145, 6854.451643868579, 6981.755638552626, 785.7053604898164, 838.8783626374142, 925.6019911438011, 941.0824446164935, 998.9001099889953, 21606.351809445743, 27450.176494705192, 28007.859960700473, 28982.69577869001, 31008.68991310058, 953.666216824106, 960.736631329799, 999.8350272205005, 1030.057497389895, 1081.7836432713489, 15569.0, 15807.525774226791, 15931.362745490282, 16132.0, 16311.0, 633431.0, 638639.0, 642943.0, 647209.0, 647599.0, 58156.41843581595, 58712.706436467546, 58947.41052589505, 59106.40893591095, 59396.109058364156, 1241.6523373455448, 1269.6571925580042, 1302.4920281090383, 1302.863199364072, 1304.4977683591885, 23398.883005584863, 25425.364365891, 26611.0, 30172.245693856952, 30944.690553095083, 49151.75424122856, 51060.744696276284, 51072.95708171632, 51141.0, 51288.0, 610785.0, 614003.0, 617621.0, 618274.0, 620307.0, 26871.059512916952, 26891.596626051018, 26912.461750765266, 27220.31949201286, 27293.181204563894, 46668.0, 46948.530514694416, 47468.287975679705, 47967.52032479631, 48213.276800847314, 39191.41212881866, 39462.80268598639, 39497.21005579815, 39733.80133099432, 40694.0, 38678.033049173995, 38895.805520972215, 39792.60207397889, 40067.1986560273, 40139.1972160561, 38246.85259442221, 38343.808280958416, 38361.040973975876, 39500.40749388819, 39972.40041399323, 32373.028809135765, 33038.17404564809, 34934.475982860786, 35033.474497882045, 35176.82411587926, 25753.742462575134, 25784.864671657677, 26017.609735854356, 26029.0, 26177.73822261753], "value": 19551.478813793943, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [365.9085228692827, 371.9665230129288, 377.5639136797, 379.98670046548375, 397.0649121319293, 19261.711074333896, 19431.222751089957, 19437.70843437348, 19909.701354479676, 20045.899770501128, 16616.335346586122, 16809.24358403872, 16924.323027078903, 19418.611627767437, 19963.500912477197, 5788.9579875622585, 6289.874202515947, 6527.902081468794, 6819.0, 6850.1779786425695, 6617.239017512999, 6747.696353664097, 6776.390124888759, 6779.559328643622, 6781.355771201728, 5696.401877802833, 6429.6142231466265, 6703.195616525999, 6719.428848547879, 6742.2246441659345, 6647.734090636393, 6692.564983276072, 6718.8992165117315, 6742.696578653948, 7315.597642129681, 7414.110306763169, 7564.319211270957, 7579.658915348822, 7597.278258565428, 7599.468037237396, 23249.070037198493, 25637.359066023313, 26947.59578606303, 28192.718072819214, 28424.147275581767, 2343.5781559319316, 2440.1581454398206, 2497.475530138673, 2543.224316583441, 2902.8983985560403, 12260.509579616804, 14136.787948180785, 16059.919700401424, 16121.032738035756, 17245.827541724546, 1680.529451753511, 1926.6146770645787, 1942.0775131812418, 1952.6192392483472, 2088.7806780288156, 123377.38311308475, 125656.0, 129674.0, 136346.95479567815, 139046.33020219722, 20697.793022069585, 21277.68083478749, 21330.466738331666, 21379.67930481044, 21562.46093847635, 33717.0, 34325.65674343224, 34393.0, 34453.0, 34485.48271775925, 10575.101116405192, 10586.57653693859, 10803.405812680377, 10831.458427078773, 10937.234393592531, 8774.95612521946, 8878.023417424078, 8886.24466920319, 8960.596773145224, 9052.185303322733, 7832.825076238496, 9512.619495220113, 9672.195063431525, 9720.416774993522, 9781.16860066888, 3198.98400507996, 3346.263821959165, 3594.51474051005, 3728.3595218103947, 3824.2925058863643, 807.3904202327205, 824.7402068348451, 944.7449188719007, 978.9314747967574, 1024.073213741565, 20090.0, 20765.377038688865, 20855.687164692543, 20985.895070524854, 21753.564928701653, 827.9668813247523, 829.2619568583965, 949.126803340928, 949.7768024514234, 1017.8167929772712, 10496.160307175407, 10855.945720271506, 10886.40124793144, 10917.290376125526, 10996.890031099587, 500213.0, 586424.0, 589291.0, 598795.0, 613231.0, 90415.0, 115007.0, 117034.80756346975, 119619.40190298992, 122183.0, 977.237754551451, 993.2550587059706, 1029.0584115534352, 1031.5718976624767, 1033.0444338986408, 19327.613447731248, 19362.8063719361, 19643.70534442013, 19694.507637309183, 19721.901390492956, 53938.0, 55179.44820551743, 56135.71932140313, 56992.86014279608, 57308.140377893535, 79097.60451197707, 80031.59984200042, 80976.0, 81323.5933820327, 83539.5823020881, 6893.586384816927, 8008.959955200186, 8066.475679080841, 8172.8365432689825, 8281.047679516932, 10555.894441055798, 12758.553450629184, 13147.342632868127, 13204.27376494302, 13254.53609123676, 9786.951065244913, 10961.232713709964, 11114.555417783522, 11225.71935701614, 11403.087752979576, 9777.653363192925, 10807.459627018461, 10813.783724325627, 11169.664910052712, 11369.658910232707, 10369.274150809673, 11080.667579972614, 11260.09919206478, 11467.541298347973, 11549.191556590958, 7371.373433258019, 7406.333429991327, 7460.552366858006, 7569.826676865095, 7677.654505547375, 8871.689490867788, 9311.0, 10841.403722795007, 11070.28043177191, 11217.887821121685], "value": 10730.88805493509, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.5214331413952, 1517.0, 1718.87967842251, 1806.4670922077985, 1850.8611854110945, 7982.92017079829, 8225.136360682132, 8452.830943381128, 9027.961784394789, 9048.72853814385, 8468.703595374163, 8758.868616970734, 8763.737087887357, 8859.645614175426, 8879.200871921526, 2333.393317737388, 2374.857508549484, 2377.072941552796, 2377.6552399902002, 2382.309130352196, 2174.6411842046045, 2282.338121944634, 2371.2293504610984, 2378.940526486843, 2383.8450500717486, 2227.265002549163, 2231.843770936035, 2282.6461898405773, 2352.5059737455153, 2366.100881664965, 2332.183735692494, 2342.8360014798973, 2359.1271229645054, 2360.752121027293, 2364.3498038039506, 1823.635272945403, 1924.288013435024, 1939.854510911673, 1996.17158879065, 2311.456807650201, 7417.369523590502, 8884.0, 8911.465312081296, 8954.597043133075, 8977.0, 5175.249588809639, 5349.3045904032615, 5353.304070470853, 5354.598405119593, 5362.0616392131615, 3454.7236221102503, 3469.0, 3651.269746050774, 3651.72612054097, 3676.2279921216577, 1070.5289672544056, 1077.9137668986461, 1078.094400703405, 1097.6597254851022, 1098.181854518381, 11686.006689431324, 11721.179517433698, 11860.347680877549, 11901.404929753564, 11970.700732481671, 4557.470603814914, 4627.305904114342, 4795.808167673324, 4810.148092984226, 4820.8794780130775, 9655.855162172573, 9816.460094694718, 9836.754081148028, 9877.160441362565, 9888.50557472138, 5288.497592728685, 5308.362996440374, 5341.0, 5345.037893179265, 5352.00987817255, 4370.584794444522, 4519.276915693474, 4701.341812146302, 4814.855554333376, 4880.0, 3855.652991230803, 3884.4173373994126, 3967.6627486663956, 4025.1144748155943, 4026.3960405938756, 1278.8145718870899, 1283.794592865137, 1288.8002359634272, 1295.591888555102, 1297.8118172864881, 796.7649543384715, 873.3275377959063, 902.1609902790336, 929.6937802387656, 938.2447130060367, 5655.236543066716, 5861.882762344814, 5918.556108291809, 5990.940090599125, 6022.548308876764, 811.350919264586, 884.0, 894.5437826708295, 908.7818923458454, 927.9536023198813, 3879.8060096995036, 3902.23906338267, 3914.2562913047, 3973.7417067890497, 4008.8396464141106, 10395.32430392022, 10908.563657453771, 10912.781744365067, 10943.726406839733, 10992.505337259843, 10828.0, 10831.512581933832, 10868.347899126078, 10892.183086268562, 10896.019358257794, 7824.882626760603, 8162.0, 8214.8357032859, 8255.628496717782, 8320.209580089882, 5874.530037596896, 5924.4667979882015, 5948.0, 5983.820485385445, 6161.0, 9918.355306905316, 10172.796544069226, 10215.0, 10221.9488902555, 10266.178705703376, 10060.39637621745, 10175.949120254352, 10268.383896966207, 10335.793284134426, 10404.58381664725, 6949.200841903245, 7175.282471752992, 7396.630168491662, 7551.207123252199, 7653.387728981779, 8400.957995209985, 8538.70114545988, 8561.1866872647, 8563.529005904486, 8586.141385861363, 6380.393862583047, 7374.815629609303, 7482.812929676802, 7547.320741133324, 7623.504472209511, 7292.161401438902, 7319.0, 7370.889436658347, 7388.076490438695, 7539.8492030157995, 6550.80347589554, 6563.507736919845, 6650.501212409185, 6651.268360480247, 6672.966635166793, 4942.629302802232, 5016.54851063406, 5028.622853285945, 5036.697798132123, 5101.387833460009, 4889.46215916256, 4970.465062817917, 5067.366579177603, 5196.116660167856, 5209.114450543341], "value": 4569.177620729773, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [272.9017553680675, 273.82064747590323, 300.23889440268914, 306.7729879888883, 315.42435056022765, 4762.8571142865685, 4762.880927976802, 4831.710097394159, 5157.79368825247, 5542.695151766651, 4939.925901111486, 5067.049186063365, 5461.399246082938, 5537.889242215153, 5578.30271216098, 4047.332190188623, 4129.690273229505, 4136.503619565657, 4192.2663533881605, 4209.663226941837, 4194.203101410722, 4199.853005144821, 4219.620234178923, 4237.724547904391, 4243.702940794145, 3427.2974040321674, 3832.386818109103, 3866.0, 4162.666986641077, 4180.372944058385, 4024.3359845625437, 4133.91732165355, 4163.708540402173, 4224.61978421942, 4240.300350442173, 3320.269540701042, 3330.2673411849355, 3636.1455058061333, 3640.908977275589, 3643.890683279506, 5368.644597126796, 5397.946020539784, 5463.31708536433, 5464.672119672832, 5470.671759694431, 4630.8147674093, 4729.385179926588, 4818.325434439182, 4829.806807727687, 4848.490908454597, 4965.826196083156, 5123.359580052494, 5613.950907918621, 5744.626599271034, 5800.593958422912, 3353.815540145291, 3475.322312149132, 3527.4356103023392, 4002.698448392186, 4035.3341698619693, 4546.318052292149, 5735.799247026334, 5891.941080589225, 5946.464818166386, 6085.9087113692885, 3549.8402571884326, 3592.1199306170156, 4068.805987299955, 4091.2635725569685, 4118.691098167649, 3650.3429382710838, 4161.563035881189, 4166.0, 4169.60388763067, 4169.770662613585, 3862.517185351831, 4723.866272094741, 4783.4499032611, 4814.290926721033, 4826.7827947742435, 4938.987507560942, 5419.945800542023, 5638.633488823213, 5894.351621321651, 5926.429496183515, 1363.6454521824396, 1390.3951780975308, 1393.303348325837, 1398.7342404943029, 1404.768213244803, 762.801671565397, 789.0767801672057, 827.6234313387395, 843.4854738609444, 845.5307304446092, 4582.754450319319, 4817.855464336006, 5431.121505602037, 5650.191938579697, 5659.886802263932, 5326.493983071602, 5403.648762830403, 5408.567314614821, 5463.31708536433, 6183.628982261079, 4869.756512174377, 4884.780184891689, 5236.188390799432, 5533.142362933751, 5592.888142237214, 2749.986250068737, 2999.505081661501, 3145.811251324928, 3193.5209718542405, 3199.3441344524317, 5904.3210030847, 6184.721687523982, 6296.433321001132, 6324.747010119544, 6374.840628984312, 5326.760295786699, 5460.31746031746, 6157.353477884758, 6171.747312231003, 6182.6599537024995, 5353.625246232727, 5962.016267315845, 6266.905996410058, 6268.247810262797, 6273.905891411632, 5306.389765176899, 5807.883842323045, 6000.369961154191, 6027.246594175728, 6038.5169186465855, 4898.118338699069, 5064.898067302029, 5450.563954883679, 6016.488598469179, 6039.6678182698615, 4950.6039516837845, 5454.0, 6331.353847999461, 6343.429091381798, 6361.61830290184, 3438.2951494943977, 3741.2704522618915, 3890.0, 3964.623360780721, 3970.861019864291, 5689.54483641316, 5897.351291357862, 5909.331628013821, 5910.202122713551, 5935.299438755443, 4662.347271381941, 4983.352164218629, 5104.821331253388, 5780.566457515619, 5867.530597552271, 4676.018036212297, 4915.21356582945, 5826.592138550261, 5847.7368518417625, 5985.341612422717, 5192.532672059533, 5803.47768700819, 5811.157382179645, 5835.0, 5910.970445147746, 4972.378452693413, 5477.014137455296, 5719.342275638355, 5860.355360910382, 5887.676177810262, 5200.985807767449, 5801.3908539602735, 5818.359980402068, 5835.7373918174635, 5912.911306330322], "value": 4182.567524191009, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [670.158950517101, 678.684411748537, 688.828990715783, 719.8812195987663, 769.8152443413583, 13993.650158746037, 14684.0, 14687.632809179775, 14692.338844751985, 14714.337854796546, 13278.203307801514, 13372.19766813992, 13375.799363009563, 13389.188270463215, 13395.397207125678, 653.2455014458309, 679.813051410861, 808.1958451340895, 815.8245977114934, 839.95380254086, 822.5106061893164, 828.8342331533687, 843.28320927212, 846.6571038729294, 846.7967687754925, 915.2540679346312, 919.4207649181006, 920.6685593186481, 922.0502882031485, 922.7508572685371, 896.3725392225435, 901.3375169250595, 904.4889637354873, 904.8597467392598, 905.3662436294554, 642.408983734965, 681.7647911470535, 682.4881338995754, 683.1494788987692, 683.8666460040295, 14415.279236038261, 14461.78307325391, 14493.202873841934, 14676.779848302283, 14683.48607798722, 14222.288885555681, 14222.359993800304, 14256.643583910381, 14368.497102601359, 14401.135931844017, 11716.53133874644, 11756.706082347924, 11784.0, 11881.524739010429, 12223.877761222362, 3712.6101759315425, 3773.754705944105, 3777.6788972937334, 3898.629630185128, 3913.119548101683, 112175.0, 113719.43140284246, 114078.0, 114553.42723286331, 117753.41123294328, 9094.363394562317, 9142.954285228532, 9283.303752218611, 10212.234082443847, 10623.256372054037, 20621.484462888548, 20990.475238118863, 21019.369418917457, 21030.369088927357, 21066.47333816667, 6578.539502234893, 7400.5189662671755, 7588.696452141852, 7635.121960974448, 7736.0, 3321.4021476134044, 3772.358699021173, 3850.9229815403537, 4254.127903779718, 4272.679549033773, 3419.042668052949, 3560.64393560647, 3564.7682900611376, 3566.144125409883, 3566.6789988901123, 652.7193306877994, 667.8196886840623, 677.5155763628931, 681.8534015186659, 682.1166589266911, 512.9589632829365, 520.5003196930913, 606.4663096475074, 607.7538596868276, 624.9781257656051, 7851.528908265523, 9187.586558604879, 9389.248860091177, 9409.341346105706, 9551.61793528265, 603.1856993059334, 625.383996763188, 627.3412916437723, 627.9937200627935, 643.3470027921663, 3394.4399174136483, 4431.578999994996, 4459.9331010034875, 4559.6808223424705, 4671.002027830257, 118958.8104118965, 128026.35986820006, 133327.0, 157284.0, 157628.0, 132576.0, 133941.0, 136585.0, 137040.0, 139300.1944737468, 85426.0, 109002.45498772615, 110636.0, 111278.0, 120263.99999999825, 7099.929000709927, 8538.743837684879, 8890.244329232088, 8946.0, 9082.500462474627, 61573.07640385487, 63490.04764928622, 65764.67117664381, 66927.3307266921, 67613.66193169003, 78546.21453785388, 80978.59510702644, 86609.16840727278, 87145.56427217824, 88791.55604221938, 12574.559890403792, 12686.936565317113, 12712.6821829455, 12758.362081895682, 13294.933525332312, 14185.858141418454, 14215.64460888486, 14385.784213237019, 14796.0, 14972.850271497582, 13214.405351759386, 13385.775743889937, 13472.932635336761, 13556.525521606696, 13816.723665526431, 11473.254238474472, 12947.676308091995, 13995.790063148857, 13995.790063149265, 13997.300134993413, 11840.0, 12933.54732584355, 13371.598852034454, 13406.93296533511, 13416.664583385493, 10817.134629229486, 11116.44417779124, 11433.542658293907, 11541.942290288494, 11583.015443687382, 9137.680181193626, 10063.081706305382, 10227.515090792733, 11341.432928353384, 11382.203245772716], "value": 7595.206468274177, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1610.8999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 118.0, 120.0, 119.0, 118.0, 113.0, 118.0, 119.0, 137.0, 172.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [156.0, 145.0, 144.0, 171.0, 121.0, 138.0, 149.0, 119.0, 183.0, 148.0], "value": 147.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [209.0, 209.0, 199.0, 203.0, 192.0, 197.0, 196.0, 192.0, 201.0, 197.0], "value": 199.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 57.0, 85.0, 61.0, 62.0, 57.0, 64.0, 73.0, 67.0], "value": 66.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 71.0, 93.0, 96.0, 102.0, 103.0, 96.0, 69.0, 70.0, 91.0], "value": 87.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 80.0, 136.0, 125.0, 89.0, 84.0, 89.0, 93.0, 89.0, 87.0], "value": 96.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 101.0, 143.0, 150.0, 155.0, 96.0, 95.0, 94.0, 96.0, 94.0], "value": 111.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 141.0, 149.0, 92.0, 90.0, 90.0, 100.0, 93.0, 91.0, 90.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 63.0, 70.0, 90.0, 91.0, 75.0, 59.0, 59.0], "value": 67.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 73.0, 74.0, 82.0, 76.0, 70.0, 74.0, 72.0, 76.0, 109.0], "value": 77.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [106.0, 89.0, 90.0, 92.0, 87.0, 91.0, 88.0, 87.0, 91.0, 103.0], "value": 92.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 147.0, 146.0, 148.0, 151.0, 145.0, 151.0, 161.0, 203.0, 304.0], "value": 170.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 202.0, 241.0, 222.0, 191.0, 176.0, 178.0, 210.0, 173.0, 176.0], "value": 195.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 72.0, 68.0, 71.0, 70.0, 70.0, 67.0, 73.0, 70.0], "value": 70.1, "unit": "score"}]}]} 18:41:03 INFO - Return code: 0 18:41:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 18:41:03 ERROR - # TBPL SUCCESS # 18:41:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:41:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:41:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:41:03 INFO - 2016-10-13 18:41:03.474 screenresolution[2268:27121] starting screenresolution argv=screenresolution get 18:41:03 INFO - 2016-10-13 18:41:03.477 screenresolution[2268:27121] Display 0: 1600x1200x32@60 18:41:03 INFO - 2016-10-13 18:41:03.484 screenresolution[2269:27124] starting screenresolution argv=screenresolution list 18:41:03 INFO - Available Modes on Display 0 18:41:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:41:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:41:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:41:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:41:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:41:03 INFO - Intel Iris: 18:41:03 INFO - Chipset Model: Intel Iris 18:41:03 INFO - Type: GPU 18:41:03 INFO - Bus: Built-In 18:41:03 INFO - VRAM (Dynamic, Max): 1536 MB 18:41:03 INFO - Vendor: Intel (0x8086) 18:41:03 INFO - Device ID: 0x0a2e 18:41:03 INFO - Revision ID: 0x0009 18:41:03 INFO - Displays: 18:41:03 INFO - Display: 18:41:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:41:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:41:03 INFO - Display Serial Number: 1600x1200 60 18:41:03 INFO - Main Display: Yes 18:41:03 INFO - Mirror: Off 18:41:03 INFO - Online: Yes 18:41:03 INFO - Rotation: Supported 18:41:03 INFO - Return code: 0 18:41:03 INFO - Running post-action listener: _resource_record_post_action 18:41:03 INFO - [mozharness: 2016-10-14 01:41:03.720574Z] Finished run-tests step (success) 18:41:03 INFO - Running post-run listener: _resource_record_post_run 18:41:03 INFO - Total resource usage - Wall time: 1211s; CPU: 54.0%; Read bytes: 943616; Write bytes: 709330944; Read time: 6; Write time: 1959 18:41:03 INFO - TinderboxPrint: CPU usage
54.4% 18:41:03 INFO - TinderboxPrint: I/O read bytes / time
943,616 / 6 18:41:03 INFO - TinderboxPrint: I/O write bytes / time
709,330,944 / 1,959 18:41:03 INFO - TinderboxPrint: CPU idle
2,208.5 (45.6%) 18:41:03 INFO - TinderboxPrint: CPU system
979.4 (20.2%) 18:41:03 INFO - TinderboxPrint: CPU user
1,657.9 (34.2%) 18:41:03 INFO - TinderboxPrint: Swap in / out
407,793,664 / 0 18:41:03 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 401394176; Write bytes: 396290048; Read time: 29541; Write time: 779 18:41:03 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 943616; Write bytes: 297959424; Read time: 6; Write time: 1135 18:41:04 INFO - Running post-run listener: _upload_blobber_files 18:41:04 INFO - Blob upload gear active. 18:41:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 18:41:04 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 18:41:04 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'] 18:41:04 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 18:41:04 INFO - (blobuploader) - INFO - Open directory for files ... 18:41:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 18:41:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 18:41:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:05 INFO - (blobuploader) - INFO - Done attempting. 18:41:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 18:41:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 18:41:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:06 INFO - (blobuploader) - INFO - Done attempting. 18:41:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 18:41:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 18:41:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 18:41:08 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 18:41:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 18:41:08 INFO - (blobuploader) - INFO - Done attempting. 18:41:08 INFO - (blobuploader) - INFO - Iteration through files over. 18:41:08 INFO - Return code: 0 18:41:08 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 18:41:08 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 18:41:08 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cbc1c9905d147b3058d7faeb303474e0d0cf7ee12cb7fb450d43876eb3fd66e0992411ebcc4000ce54202cde9dfe04e3428cf07cfeeba590d753dadd718736a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0bfc8243538b3b5434fb627ab0caf81a3e6216d4b565c5d7b31f07357983fffeb560c950e271f44e8c622b0994d885e0ce6c63d1fb8a86bceef45d0bff3b725", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d780a8eea9327da54154e21280732f4b69831054c61ca17c9eeff5e3af8d481c5cc944259cab22c2de7dc7cda5ee6de48859ed631f89493b04c0c3a754ba6db2"} 18:41:08 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 18:41:08 INFO - Writing to file /builds/slave/test/properties/blobber_files 18:41:08 INFO - Contents: 18:41:08 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/cbc1c9905d147b3058d7faeb303474e0d0cf7ee12cb7fb450d43876eb3fd66e0992411ebcc4000ce54202cde9dfe04e3428cf07cfeeba590d753dadd718736a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0bfc8243538b3b5434fb627ab0caf81a3e6216d4b565c5d7b31f07357983fffeb560c950e271f44e8c622b0994d885e0ce6c63d1fb8a86bceef45d0bff3b725", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d780a8eea9327da54154e21280732f4b69831054c61ca17c9eeff5e3af8d481c5cc944259cab22c2de7dc7cda5ee6de48859ed631f89493b04c0c3a754ba6db2"} 18:41:08 INFO - Running post-run listener: copy_logs_to_upload_dir 18:41:08 INFO - Copying logs to upload dir... 18:41:08 INFO - mkdir: /builds/slave/test/build/upload/logs 18:41:08 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.267264 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-10-13 18:41:09.021110) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 18:41:09.024269) ========= 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.POHXzahlSD/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.6eAwUATTHC/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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/cbc1c9905d147b3058d7faeb303474e0d0cf7ee12cb7fb450d43876eb3fd66e0992411ebcc4000ce54202cde9dfe04e3428cf07cfeeba590d753dadd718736a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0bfc8243538b3b5434fb627ab0caf81a3e6216d4b565c5d7b31f07357983fffeb560c950e271f44e8c622b0994d885e0ce6c63d1fb8a86bceef45d0bff3b725", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d780a8eea9327da54154e21280732f4b69831054c61ca17c9eeff5e3af8d481c5cc944259cab22c2de7dc7cda5ee6de48859ed631f89493b04c0c3a754ba6db2"} build_url:https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011198 build_url: 'https://queue.taskcluster.net/v1/task/SNhTyhNxRxuUZ7tB044PXw/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/cbc1c9905d147b3058d7faeb303474e0d0cf7ee12cb7fb450d43876eb3fd66e0992411ebcc4000ce54202cde9dfe04e3428cf07cfeeba590d753dadd718736a6", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/f0bfc8243538b3b5434fb627ab0caf81a3e6216d4b565c5d7b31f07357983fffeb560c950e271f44e8c622b0994d885e0ce6c63d1fb8a86bceef45d0bff3b725", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d780a8eea9327da54154e21280732f4b69831054c61ca17c9eeff5e3af8d481c5cc944259cab22c2de7dc7cda5ee6de48859ed631f89493b04c0c3a754ba6db2"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-13 18:41:09.056421) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 18:41:09.056706) ========= 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.POHXzahlSD/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.6eAwUATTHC/Listeners TMPDIR=/var/folders/kg/hnzl1l9x12nbzbrqlx0f6z4h00000w/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.005475 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-13 18:41:09.105465) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 18:41:09.105743) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-13 18:41:09.106072) ========= ========= Total master_lag: 0.17 =========