builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0358 starttime: 1475116388.97 results: success (0) buildid: 20160928165820 builduid: 40097a8745e2425886e384f0d4f199de revision: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:08.966591) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:08.967185) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:08.967476) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:08.998692) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:08.999094) ========= 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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-09-28 19:33:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 159M=0s 2016-09-28 19:33:09 (159 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.788401 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:09.807514) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:09.807845) ========= 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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.036417 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:09.867140) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:09.867511) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 --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 cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iZdnQXnwyk/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.hAOmQPISJ3/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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-09-28 19:33:09,955 truncating revision to first 12 chars 2016-09-28 19:33:09,955 Setting DEBUG logging. 2016-09-28 19:33:09,955 attempt 1/10 2016-09-28 19:33:09,955 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/cde8b9afb577?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 19:33:10,650 unpacking tar archive at: autoland-cde8b9afb577/testing/mozharness/ program finished with exit code 0 elapsedTime=0.962227 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:10.852009) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:10.852318) ========= script_repo_revision: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:10.852742) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:10.853028) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 19:33:10.871286) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-28 19:33:10.871678) ========= /tools/buildbot/bin/python 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', '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:11 INFO - MultiFileLogger online at 20160928 19:33:11 in /builds/slave/test 19:33:11 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 19:33:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:33:11 INFO - {'append_to_log': False, 19:33:11 INFO - 'base_work_dir': '/builds/slave/test', 19:33:11 INFO - 'blob_upload_branch': 'Autoland', 19:33:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:33:11 INFO - 'branch': 'Autoland', 19:33:11 INFO - 'buildbot_json_path': 'buildprops.json', 19:33:11 INFO - 'config_files': ('talos/mac_config.py',), 19:33:11 INFO - 'default_actions': ('clobber', 19:33:11 INFO - 'read-buildbot-config', 19:33:11 INFO - 'download-and-extract', 19:33:11 INFO - 'populate-webroot', 19:33:11 INFO - 'create-virtualenv', 19:33:11 INFO - 'install', 19:33:11 INFO - 'run-tests'), 19:33:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:33:11 INFO - 'download_minidump_stackwalk': True, 19:33:11 INFO - 'download_symbols': 'ondemand', 19:33:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:33:11 INFO - 'tooltool.py': '/tools/tooltool.py', 19:33:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:33:11 INFO - '/tools/misc-python/virtualenv.py')}, 19:33:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:33:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:33:11 INFO - 'installer_path': 'installer.exe', 19:33:11 INFO - 'log_level': 'info', 19:33:11 INFO - 'log_name': 'talos', 19:33:11 INFO - 'log_to_console': True, 19:33:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:33:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:33:11 INFO - 'opt_config_files': (), 19:33:11 INFO - 'pip_index': False, 19:33:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:33:11 INFO - 'cmd': ('bash', 19:33:11 INFO - '-c', 19:33:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:33:11 INFO - 'enabled': True, 19:33:11 INFO - 'halt_on_failure': False, 19:33:11 INFO - 'name': 'check_screen_resolution'},), 19:33:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:33:11 INFO - 'cmd': ('bash', 19:33:11 INFO - '-c', 19:33:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:33:11 INFO - 'enabled': True, 19:33:11 INFO - 'halt_on_failure': False, 19:33:11 INFO - 'name': 'check_screen_resolution'},), 19:33:11 INFO - 'run_cmd_checks_enabled': True, 19:33:11 INFO - 'sps_profile': False, 19:33:11 INFO - 'sps_profile_interval': 0, 19:33:11 INFO - 'suite': 'dromaeojs-e10s', 19:33:11 INFO - 'system_bits': '32', 19:33:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:33:11 INFO - 'title': 't-yosemite-r7-0358', 19:33:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:33:11 INFO - 'use_talos_json': True, 19:33:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:33:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:33:11 INFO - 'work_dir': 'build'} 19:33:11 INFO - [mozharness: 2016-09-29 02:33:11.079902Z] Running clobber step. 19:33:11 INFO - Running pre-action listener: _resource_record_pre_action 19:33:11 INFO - Running main action method: clobber 19:33:11 INFO - rmtree: /builds/slave/test/build 19:33:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:33:13 INFO - Running post-action listener: _resource_record_post_action 19:33:13 INFO - [mozharness: 2016-09-29 02:33:13.374975Z] Finished clobber step (success) 19:33:13 INFO - [mozharness: 2016-09-29 02:33:13.375100Z] Running read-buildbot-config step. 19:33:13 INFO - Running pre-action listener: _resource_record_pre_action 19:33:13 INFO - Running main action method: read_buildbot_config 19:33:13 INFO - Using buildbot properties: 19:33:13 INFO - { 19:33:13 INFO - "project": "", 19:33:13 INFO - "product": "firefox", 19:33:13 INFO - "script_repo_revision": "production", 19:33:13 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 19:33:13 INFO - "repository": "", 19:33:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 19:33:13 INFO - "buildid": "20160928165820", 19:33:13 INFO - "pgo_build": "False", 19:33:13 INFO - "basedir": "/builds/slave/test", 19:33:13 INFO - "buildnumber": 164, 19:33:13 INFO - "slavename": "t-yosemite-r7-0358", 19:33:13 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:33:13 INFO - "platform": "yosemite_r7", 19:33:13 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 19:33:13 INFO - "branch": "autoland", 19:33:13 INFO - "repo_path": "integration/autoland", 19:33:13 INFO - "revision": "cde8b9afb57787e05bee1c891fa9d34bb5bf87a6", 19:33:13 INFO - "stage_platform": "macosx64", 19:33:13 INFO - "builduid": "40097a8745e2425886e384f0d4f199de", 19:33:13 INFO - "slavebuilddir": "test" 19:33:13 INFO - } 19:33:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 19:33:13 INFO - Running post-action listener: _resource_record_post_action 19:33:13 INFO - [mozharness: 2016-09-29 02:33:13.378460Z] Finished read-buildbot-config step (success) 19:33:13 INFO - [mozharness: 2016-09-29 02:33:13.378588Z] Running download-and-extract step. 19:33:13 INFO - Running pre-action listener: _resource_record_pre_action 19:33:13 INFO - Running main action method: download_and_extract 19:33:13 INFO - mkdir: /builds/slave/test/build/tests 19:33:13 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')]} 19:33:13 INFO - trying https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 19:33:13 INFO - Downloading https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/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 19:33:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/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 19:33:14 INFO - Downloaded 1199 bytes. 19:33:14 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 19:33:14 INFO - Using the following test package requirements: 19:33:14 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:33:14 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 19:33:14 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 19:33:14 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'jsshell-mac.zip'], 19:33:14 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 19:33:14 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 19:33:14 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 19:33:14 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 19:33:14 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 19:33:14 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 19:33:14 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 19:33:14 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:33:14 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:33:14 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:33:14 INFO - Fetch https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:33:15 INFO - Http code: 200 19:33:15 INFO - Content-Encoding: None 19:33:15 INFO - Content-Type: application/zip 19:33:15 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 19:33:15 INFO - x-amz-cf-id: y-kTOb4YNGopsr6guPqrBe0SlLuc6U2HWUgadAsKxcOAvTm__RybIg== 19:33:15 INFO - x-amz-version-id: KMfTt2rPDY0AFZ15q7rhO14FhtJLvwqg 19:33:15 INFO - x-cache: Hit from cloudfront 19:33:15 INFO - Expected file size: 16472838 19:33:15 INFO - Obtained file size: 16472838 19:33:22 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 19:33:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 19:33:22 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:33:22 INFO - Fetch https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 19:33:22 INFO - Http code: 200 19:33:22 INFO - Content-Encoding: None 19:33:22 INFO - Content-Type: application/zip 19:33:22 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 19:33:22 INFO - x-amz-cf-id: awNaSFJoB59WtQ4wPLbYi2F-VKSMKQbjXUx4YgT_vv16HKJsj_XSsQ== 19:33:22 INFO - x-amz-version-id: KMfTt2rPDY0AFZ15q7rhO14FhtJLvwqg 19:33:22 INFO - x-cache: Hit from cloudfront 19:33:23 INFO - Expected file size: 16472838 19:33:23 INFO - Obtained file size: 16472838 19:33:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 19:33:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:33:29 INFO - Fetch https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 19:33:30 INFO - Http code: 200 19:33:30 INFO - Content-Encoding: None 19:33:30 INFO - Content-Type: application/zip 19:33:30 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 19:33:30 INFO - x-amz-cf-id: nBc53sNOmCTqijeyP9yD4GCNrbUg1YCtkyRA2GThKZBar10UTfUOcQ== 19:33:30 INFO - x-amz-version-id: O6xu5c9l9EHAofsZquaXjowvEw4LWPEk 19:33:30 INFO - x-cache: Hit from cloudfront 19:33:30 INFO - Expected file size: 14414067 19:33:30 INFO - Obtained file size: 14414067 19:33:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:30 INFO - trying https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:33:30 INFO - Downloading https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 19:33:30 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/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 19:33:33 INFO - Downloaded 111795964 bytes. 19:33:33 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:33:33 INFO - mkdir: /builds/slave/test/properties 19:33:33 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:33:33 INFO - Writing to file /builds/slave/test/properties/build_url 19:33:33 INFO - Contents: 19:33:33 INFO - build_url:https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 19:33:34 INFO - Running post-action listener: _resource_record_post_action 19:33:34 INFO - Running post-action listener: set_extra_try_arguments 19:33:34 INFO - [mozharness: 2016-09-29 02:33:34.788632Z] Finished download-and-extract step (success) 19:33:34 INFO - [mozharness: 2016-09-29 02:33:34.788754Z] Running populate-webroot step. 19:33:34 INFO - Running pre-action listener: _resource_record_pre_action 19:33:34 INFO - Running main action method: populate_webroot 19:33:34 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:33:34 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:33:34 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:33:34 INFO - '--tppagecycles', 19:33:34 INFO - '7'], 19:33:34 INFO - 'tests': ['tsvgm']}}, 19:33:34 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:33:34 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:33:34 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:33:34 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:33:34 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:33:34 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:33:34 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['damp', 'tps']}, 19:33:34 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'tests': ['damp', 'tps']}, 19:33:34 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['dromaeo_dom']}, 19:33:34 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:33:34 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['basic_compositor_video']}, 19:33:34 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:33:34 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['a11yr', 19:33:34 INFO - 'ts_paint', 19:33:34 INFO - 'tpaint', 19:33:34 INFO - 'sessionrestore', 19:33:34 INFO - 'sessionrestore_no_auto_restore', 19:33:34 INFO - 'tabpaint']}, 19:33:34 INFO - 'other-e10s': {'tests': ['a11yr', 19:33:34 INFO - 'ts_paint', 19:33:34 INFO - 'tpaint', 19:33:34 INFO - 'sessionrestore', 19:33:34 INFO - 'sessionrestore_no_auto_restore', 19:33:34 INFO - 'tabpaint']}, 19:33:34 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:33:34 INFO - 'ts_paint', 19:33:34 INFO - 'tpaint', 19:33:34 INFO - 'sessionrestore', 19:33:34 INFO - 'sessionrestore_no_auto_restore', 19:33:34 INFO - 'tabpaint']}, 19:33:34 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:33:34 INFO - 'ts_paint', 19:33:34 INFO - 'tpaint', 19:33:34 INFO - 'sessionrestore', 19:33:34 INFO - 'sessionrestore_no_auto_restore', 19:33:34 INFO - 'tabpaint']}, 19:33:34 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['a11yr', 19:33:34 INFO - 'ts_paint', 19:33:34 INFO - 'tpaint', 19:33:34 INFO - 'sessionrestore', 19:33:34 INFO - 'sessionrestore_no_auto_restore', 19:33:34 INFO - 'tabpaint']}, 19:33:34 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['a11yr', 19:33:34 INFO - 'ts_paint', 19:33:34 INFO - 'tpaint', 19:33:34 INFO - 'sessionrestore', 19:33:34 INFO - 'sessionrestore_no_auto_restore', 19:33:34 INFO - 'tabpaint']}, 19:33:34 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['tsvgx', 19:33:34 INFO - 'tsvgr_opacity', 19:33:34 INFO - 'tart', 19:33:34 INFO - 'tscrollx', 19:33:34 INFO - 'cart']}, 19:33:34 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:33:34 INFO - 'tsvgr_opacity', 19:33:34 INFO - 'tart', 19:33:34 INFO - 'tscrollx', 19:33:34 INFO - 'cart']}, 19:33:34 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'talos_options': ['--disable-e10s'], 19:33:34 INFO - 'tests': ['tp5o']}, 19:33:34 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'tests': ['tp5o']}, 19:33:34 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'talos_options': ['--disable-e10s', 19:33:34 INFO - '--xperf_path', 19:33:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:33:34 INFO - 'tests': ['tp5n']}, 19:33:34 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:33:34 INFO - 'talos_options': ['--xperf_path', 19:33:34 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:33:34 INFO - 'tests': ['tp5n']}}, 19:33:34 INFO - 'talos.zip': {'path': '', 19:33:34 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:33:34 INFO - Running post-action listener: _resource_record_post_action 19:33:34 INFO - [mozharness: 2016-09-29 02:33:34.807473Z] Finished populate-webroot step (success) 19:33:34 INFO - [mozharness: 2016-09-29 02:33:34.807604Z] Running create-virtualenv step. 19:33:34 INFO - Running pre-action listener: _resource_record_pre_action 19:33:34 INFO - Running main action method: create_virtualenv 19:33:34 INFO - Creating virtualenv /builds/slave/test/build/venv 19:33:34 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 19:33:34 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:33:34 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:33:34 INFO - Using real prefix '/tools/python27' 19:33:34 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:33:35 INFO - Installing distribute.............................................................................................................................................................................................done. 19:33:38 INFO - Installing pip.................done. 19:33:38 INFO - Return code: 0 19:33:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:33:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:33:38 INFO - Reading from file tmpfile_stdout 19:33:38 INFO - Output received: 19:33:38 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) 19:33:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:33:38 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')]} 19:33:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:38 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')]} 19:33:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:38 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:38 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 19:33:38 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 19:33:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:38 INFO - 'HOME': '/Users/cltbld', 19:33:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:38 INFO - 'LOGNAME': 'cltbld', 19:33:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:38 INFO - 'MOZ_NO_REMOTE': '1', 19:33:38 INFO - 'NO_EM_RESTART': '1', 19:33:38 INFO - 'PAGER': '/bin/cat', 19:33:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:38 INFO - 'PWD': '/builds/slave/test', 19:33:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:38 INFO - 'SHELL': '/bin/bash', 19:33:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:38 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:38 INFO - 'USER': 'cltbld', 19:33:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:38 INFO - 'XPC_FLAGS': '0x0', 19:33:38 INFO - 'XPC_SERVICE_NAME': '0', 19:33:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:39 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 19:33:39 INFO - Cleaning up... 19:33:39 INFO - Return code: 0 19:33:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:33:39 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')]} 19:33:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:39 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')]} 19:33:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:39 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:39 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 19:33:39 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 19:33:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:39 INFO - 'HOME': '/Users/cltbld', 19:33:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:39 INFO - 'LOGNAME': 'cltbld', 19:33:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:39 INFO - 'MOZ_NO_REMOTE': '1', 19:33:39 INFO - 'NO_EM_RESTART': '1', 19:33:39 INFO - 'PAGER': '/bin/cat', 19:33:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:39 INFO - 'PWD': '/builds/slave/test', 19:33:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:39 INFO - 'SHELL': '/bin/bash', 19:33:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:39 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:39 INFO - 'USER': 'cltbld', 19:33:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:39 INFO - 'XPC_FLAGS': '0x0', 19:33:39 INFO - 'XPC_SERVICE_NAME': '0', 19:33:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:39 INFO - Downloading/unpacking psutil>=3.1.1 19:33: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 19:33: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 19:33: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 19:33: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 19:33: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 19:33: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 19:33:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:33:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:33:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:33:43 INFO - Installing collected packages: psutil 19:33:43 INFO - Running setup.py install for psutil 19:33:43 INFO - building 'psutil._psutil_osx' extension 19:33:43 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 19:33:43 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 19:33:43 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 19:33:43 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 19:33:43 INFO - building 'psutil._psutil_posix' extension 19:33:43 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 19:33:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:33:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:33:43 INFO - ^ 19:33:44 INFO - 1 warning generated. 19:33:44 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 19:33:44 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:33:44 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:33:44 INFO - Successfully installed psutil 19:33:44 INFO - Cleaning up... 19:33:44 INFO - Return code: 0 19:33:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:44 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:44 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 19:33:44 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 19:33:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:44 INFO - 'HOME': '/Users/cltbld', 19:33:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:44 INFO - 'LOGNAME': 'cltbld', 19:33:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:44 INFO - 'MOZ_NO_REMOTE': '1', 19:33:44 INFO - 'NO_EM_RESTART': '1', 19:33:44 INFO - 'PAGER': '/bin/cat', 19:33:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:44 INFO - 'PWD': '/builds/slave/test', 19:33:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:44 INFO - 'SHELL': '/bin/bash', 19:33:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:44 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:44 INFO - 'USER': 'cltbld', 19:33:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:44 INFO - 'XPC_FLAGS': '0x0', 19:33:44 INFO - 'XPC_SERVICE_NAME': '0', 19:33:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:33:44 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 19:33:44 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 19:33:44 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 19:33:44 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 19:33:44 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 19:33:44 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 19:33:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:33:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:33:47 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:33:47 INFO - Installing collected packages: mozsystemmonitor 19:33:47 INFO - Running setup.py install for mozsystemmonitor 19:33:47 INFO - Successfully installed mozsystemmonitor 19:33:47 INFO - Cleaning up... 19:33:47 INFO - Return code: 0 19:33:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:33:47 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')]} 19:33:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 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')]} 19:33:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:47 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:47 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 19:33:47 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 19:33:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:47 INFO - 'HOME': '/Users/cltbld', 19:33:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:47 INFO - 'LOGNAME': 'cltbld', 19:33:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:47 INFO - 'MOZ_NO_REMOTE': '1', 19:33:47 INFO - 'NO_EM_RESTART': '1', 19:33:47 INFO - 'PAGER': '/bin/cat', 19:33:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:47 INFO - 'PWD': '/builds/slave/test', 19:33:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:47 INFO - 'SHELL': '/bin/bash', 19:33:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:47 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:47 INFO - 'USER': 'cltbld', 19:33:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:47 INFO - 'XPC_FLAGS': '0x0', 19:33:47 INFO - 'XPC_SERVICE_NAME': '0', 19:33:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:47 INFO - Downloading/unpacking jsonschema==2.5.1 19:33:47 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 19:33:47 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 19:33:47 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 19:33:47 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 19:33:47 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 19:33:47 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 19:33:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:33:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:33:49 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 19:33:49 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 19:33:49 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 19:33:49 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 19:33:49 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 19:33:49 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 19:33:50 INFO - Downloading functools32-3.2.3-2.tar.gz 19:33:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:33:50 INFO - warning: no files found matching '*.txt' 19:33:50 INFO - no previously-included directories found matching 'build' 19:33:50 INFO - no previously-included directories found matching 'dist' 19:33:50 INFO - no previously-included directories found matching '.git*' 19:33:50 INFO - Installing collected packages: jsonschema, functools32 19:33:50 INFO - Running setup.py install for functools32 19:33:50 INFO - warning: no files found matching '*.txt' 19:33:50 INFO - no previously-included directories found matching 'build' 19:33:50 INFO - no previously-included directories found matching 'dist' 19:33:50 INFO - no previously-included directories found matching '.git*' 19:33:50 INFO - Successfully installed jsonschema functools32 19:33:50 INFO - Cleaning up... 19:33:50 INFO - Return code: 0 19:33:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:33:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:50 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:50 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 19:33:50 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 19:33:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:50 INFO - 'HOME': '/Users/cltbld', 19:33:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:50 INFO - 'LOGNAME': 'cltbld', 19:33:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:50 INFO - 'MOZ_NO_REMOTE': '1', 19:33:50 INFO - 'NO_EM_RESTART': '1', 19:33:50 INFO - 'PAGER': '/bin/cat', 19:33:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:50 INFO - 'PWD': '/builds/slave/test', 19:33:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:50 INFO - 'SHELL': '/bin/bash', 19:33:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:50 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:50 INFO - 'USER': 'cltbld', 19:33:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:50 INFO - 'XPC_FLAGS': '0x0', 19:33:50 INFO - 'XPC_SERVICE_NAME': '0', 19:33:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:33:50 INFO - Cleaning up... 19:33:51 INFO - Return code: 0 19:33:51 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:33: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')]} 19:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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')]} 19:33:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33: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', '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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 19:33: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 blobuploader==1.2.4 19:33:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:51 INFO - 'HOME': '/Users/cltbld', 19:33:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:51 INFO - 'LOGNAME': 'cltbld', 19:33:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:51 INFO - 'MOZ_NO_REMOTE': '1', 19:33:51 INFO - 'NO_EM_RESTART': '1', 19:33:51 INFO - 'PAGER': '/bin/cat', 19:33:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:51 INFO - 'PWD': '/builds/slave/test', 19:33:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:51 INFO - 'SHELL': '/bin/bash', 19:33:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:51 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:51 INFO - 'USER': 'cltbld', 19:33:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:51 INFO - 'XPC_FLAGS': '0x0', 19:33:51 INFO - 'XPC_SERVICE_NAME': '0', 19:33:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:51 INFO - Downloading/unpacking blobuploader==1.2.4 19:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:53 INFO - Downloading blobuploader-1.2.4.tar.gz 19:33:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:33:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:33:53 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 19:33:53 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 19:33:53 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 19:33:53 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 19:33:53 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 19:33:53 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 19:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:33:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:33:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:33:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:33:54 INFO - Downloading docopt-0.6.1.tar.gz 19:33:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:33:55 INFO - Installing collected packages: blobuploader, requests, docopt 19:33:55 INFO - Running setup.py install for blobuploader 19:33:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:33:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:33:55 INFO - Running setup.py install for requests 19:33:55 INFO - Running setup.py install for docopt 19:33:55 INFO - Successfully installed blobuploader requests docopt 19:33:55 INFO - Cleaning up... 19:33:55 INFO - Return code: 0 19:33:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:33:55 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')]} 19:33:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:33:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:55 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')]} 19:33:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:33:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:33:55 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:33:55 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 19:33:55 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 19:33:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:33:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:33:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:33:55 INFO - 'HOME': '/Users/cltbld', 19:33:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:33:55 INFO - 'LOGNAME': 'cltbld', 19:33:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:33:55 INFO - 'MOZ_NO_REMOTE': '1', 19:33:55 INFO - 'NO_EM_RESTART': '1', 19:33:55 INFO - 'PAGER': '/bin/cat', 19:33:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:33:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:33:55 INFO - 'PWD': '/builds/slave/test', 19:33:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:33:55 INFO - 'SHELL': '/bin/bash', 19:33:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:33:55 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:33:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:33:55 INFO - 'USER': 'cltbld', 19:33:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:33:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:33:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:33:55 INFO - 'XPC_FLAGS': '0x0', 19:33:55 INFO - 'XPC_SERVICE_NAME': '0', 19:33:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:33:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-9wtCql-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-nxpWon-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Dik582-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-33_s5A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-sW8C6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-XbBsh_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Z8SwVY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-PftB00-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:33:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:33:56 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-BPhZJ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ARFQos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-cAPGr6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-4Mc6XH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-uWAuBt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-Ps9UCI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-ZKl5Zl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-hmJoxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:33:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:33:57 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-bIj8fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:33:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:33:57 INFO - Running setup.py install for manifestparser 19:33:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:33:58 INFO - Running setup.py install for mozcrash 19:33:58 INFO - Running setup.py install for mozdebug 19:33:58 INFO - Running setup.py install for mozdevice 19:33:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:33:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:33:58 INFO - Running setup.py install for mozfile 19:33:58 INFO - Running setup.py install for mozhttpd 19:33:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:33:58 INFO - Running setup.py install for mozinfo 19:33:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:33:58 INFO - Running setup.py install for mozInstall 19:33:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Running setup.py install for mozleak 19:33:59 INFO - Running setup.py install for mozlog 19:33:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Running setup.py install for moznetwork 19:33:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Running setup.py install for mozprocess 19:33:59 INFO - Running setup.py install for mozprofile 19:33:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:33:59 INFO - Running setup.py install for mozrunner 19:33:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:34:00 INFO - Running setup.py install for mozscreenshot 19:34:00 INFO - Running setup.py install for moztest 19:34:00 INFO - Running setup.py install for mozversion 19:34:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:34:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:34:00 INFO - Cleaning up... 19:34:00 INFO - Return code: 0 19:34:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:34:00 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')]} 19:34:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:00 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')]} 19:34:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:00 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:34:00 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 19:34:00 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 19:34:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:34:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:00 INFO - 'HOME': '/Users/cltbld', 19:34:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:00 INFO - 'LOGNAME': 'cltbld', 19:34:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:00 INFO - 'MOZ_NO_REMOTE': '1', 19:34:00 INFO - 'NO_EM_RESTART': '1', 19:34:00 INFO - 'PAGER': '/bin/cat', 19:34:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:00 INFO - 'PWD': '/builds/slave/test', 19:34:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:00 INFO - 'SHELL': '/bin/bash', 19:34:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:34:00 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:34:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:00 INFO - 'USER': 'cltbld', 19:34:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:00 INFO - 'XPC_FLAGS': '0x0', 19:34:00 INFO - 'XPC_SERVICE_NAME': '0', 19:34:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:34:00 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-eMxbSO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:34:00 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)) 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:34:00 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-xIAoRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:34:00 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)) 19:34:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:34:00 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-sycSQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-HqLwsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-UTv6On-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-AvxKHS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-jvrMWy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-PYGv6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-QxgTqA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-IO4nWq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-1w3vpT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:34:01 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-XRFa7r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:34:01 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)) 19:34:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:34:02 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-8t77ht-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:34:02 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-15VZwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:34:02 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-FSQmM9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:34:02 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-EHwh3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:34:02 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)) 19:34:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:34:02 INFO - Running setup.py (path:/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/pip-hRBp4q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:34:02 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)) 19:34:02 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)) 19:34:02 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)) 19:34:02 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)) 19:34:02 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)) 19:34:02 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)) 19:34:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:34:02 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 19:34:02 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 19:34:02 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 19:34:02 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 19:34:02 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 19:34:02 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 19:34:04 INFO - Downloading blessings-1.6.tar.gz 19:34:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:34:05 INFO - Installing collected packages: blessings 19:34:05 INFO - Running setup.py install for blessings 19:34:05 INFO - Successfully installed blessings 19:34:05 INFO - Cleaning up... 19:34:05 INFO - Return code: 0 19:34:05 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:34:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:34:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:34:05 INFO - Reading from file tmpfile_stdout 19:34:05 INFO - Current package versions: 19:34:05 INFO - blessings == 1.6 19:34:05 INFO - blobuploader == 1.2.4 19:34:05 INFO - docopt == 0.6.1 19:34:05 INFO - functools32 == 3.2.3-2 19:34:05 INFO - jsonschema == 2.5.1 19:34:05 INFO - manifestparser == 1.1 19:34:05 INFO - mozInstall == 1.12 19:34:05 INFO - mozcrash == 0.17 19:34:05 INFO - mozdebug == 0.1 19:34:05 INFO - mozdevice == 0.48 19:34:05 INFO - mozfile == 1.2 19:34:05 INFO - mozhttpd == 0.7 19:34:05 INFO - mozinfo == 0.9 19:34:05 INFO - mozleak == 0.1 19:34:05 INFO - mozlog == 3.4 19:34:05 INFO - moznetwork == 0.27 19:34:05 INFO - mozprocess == 0.23 19:34:05 INFO - mozprofile == 0.28 19:34:05 INFO - mozrunner == 6.12 19:34:05 INFO - mozscreenshot == 0.1 19:34:05 INFO - mozsystemmonitor == 0.3 19:34:05 INFO - moztest == 0.7 19:34:05 INFO - mozversion == 1.4 19:34:05 INFO - psutil == 3.1.1 19:34:05 INFO - requests == 1.2.3 19:34:05 INFO - wsgiref == 0.1.2 19:34:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:34:05 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')]} 19:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:05 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')]} 19:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:05 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:34:05 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 19:34:05 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 19:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:05 INFO - 'HOME': '/Users/cltbld', 19:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:05 INFO - 'LOGNAME': 'cltbld', 19:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:05 INFO - 'MOZ_NO_REMOTE': '1', 19:34:05 INFO - 'NO_EM_RESTART': '1', 19:34:05 INFO - 'PAGER': '/bin/cat', 19:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:05 INFO - 'PWD': '/builds/slave/test', 19:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:05 INFO - 'SHELL': '/bin/bash', 19:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:34:05 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:05 INFO - 'USER': 'cltbld', 19:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:05 INFO - 'XPC_FLAGS': '0x0', 19:34:05 INFO - 'XPC_SERVICE_NAME': '0', 19:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 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)) 19:34:05 INFO - Cleaning up... 19:34:05 INFO - Return code: 0 19:34:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:34:05 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')]} 19:34:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:34:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:05 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')]} 19:34:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:34:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:34:05 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 0x108372490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082e8570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5284d2000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, '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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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 19:34:05 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 19:34:05 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 19:34:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:34:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:05 INFO - 'HOME': '/Users/cltbld', 19:34:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:05 INFO - 'LOGNAME': 'cltbld', 19:34:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:05 INFO - 'MOZ_NO_REMOTE': '1', 19:34:05 INFO - 'NO_EM_RESTART': '1', 19:34:05 INFO - 'PAGER': '/bin/cat', 19:34:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:05 INFO - 'PWD': '/builds/slave/test', 19:34:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:05 INFO - 'SHELL': '/bin/bash', 19:34:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:34:05 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:34:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:05 INFO - 'USER': 'cltbld', 19:34:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:05 INFO - 'XPC_FLAGS': '0x0', 19:34:05 INFO - 'XPC_SERVICE_NAME': '0', 19:34:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:34:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:34:06 INFO - Cleaning up... 19:34:06 INFO - Return code: 0 19:34:06 INFO - Running post-action listener: _resource_record_post_action 19:34:06 INFO - Running post-action listener: _start_resource_monitoring 19:34:06 INFO - Starting resource monitoring. 19:34:06 INFO - [mozharness: 2016-09-29 02:34:06.156812Z] Finished create-virtualenv step (success) 19:34:06 INFO - [mozharness: 2016-09-29 02:34:06.157799Z] Running install step. 19:34:06 INFO - Running pre-action listener: _resource_record_pre_action 19:34:06 INFO - Running main action method: install 19:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:34:06 INFO - Reading from file tmpfile_stdout 19:34:06 INFO - Detecting whether we're running mozinstall >=1.0... 19:34:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:34:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:34:06 INFO - Reading from file tmpfile_stdout 19:34:06 INFO - Output received: 19:34:06 INFO - Usage: mozinstall [options] installer 19:34:06 INFO - Options: 19:34:06 INFO - -h, --help show this help message and exit 19:34:06 INFO - -d DEST, --destination=DEST 19:34:06 INFO - Directory to install application into. [default: 19:34:06 INFO - "/builds/slave/test"] 19:34:06 INFO - --app=APP Application being installed. [default: firefox] 19:34:06 INFO - mkdir: /builds/slave/test/build/application 19:34:06 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'] 19:34:06 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 19:34:41 INFO - Reading from file tmpfile_stdout 19:34:41 INFO - Output received: 19:34:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:34:41 INFO - Running post-action listener: _resource_record_post_action 19:34:41 INFO - [mozharness: 2016-09-29 02:34:41.125845Z] Finished install step (success) 19:34:41 INFO - [mozharness: 2016-09-29 02:34:41.125970Z] Running run-tests step. 19:34:41 INFO - Running pre-action listener: _resource_record_pre_action 19:34:41 INFO - Running main action method: run_tests 19:34:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:34:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:34:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:34:41 INFO - 2016-09-28 19:34:41.140 screenresolution[1647:8327] starting screenresolution argv=screenresolution get 19:34:41 INFO - 2016-09-28 19:34:41.143 screenresolution[1647:8327] Display 0: 1600x1200x32@60 19:34:41 INFO - 2016-09-28 19:34:41.148 screenresolution[1648:8329] starting screenresolution argv=screenresolution list 19:34:41 INFO - Available Modes on Display 0 19:34:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:34:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:34:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:34:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:34:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:34:41 INFO - Intel Iris: 19:34:41 INFO - Chipset Model: Intel Iris 19:34:41 INFO - Type: GPU 19:34:41 INFO - Bus: Built-In 19:34:41 INFO - VRAM (Dynamic, Max): 1536 MB 19:34:41 INFO - Vendor: Intel (0x8086) 19:34:41 INFO - Device ID: 0x0a2e 19:34:41 INFO - Revision ID: 0x0009 19:34:41 INFO - Displays: 19:34:41 INFO - Display: 19:34:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:34:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:34:41 INFO - Display Serial Number: 1600x1200 60 19:34:41 INFO - Main Display: Yes 19:34:41 INFO - Mirror: Off 19:34:41 INFO - Online: Yes 19:34:41 INFO - Rotation: Supported 19:34:41 INFO - Return code: 0 19:34:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:34:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:34:41 INFO - Python 2.7.3 19:34:41 INFO - Return code: 0 19:34:41 INFO - grabbing minidump binary from tooltool 19:34: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')]} 19:34:41 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 0x108186d30>, '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 0x107facdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10817eb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:34:41 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 19:34:41 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 19:34:41 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 19:34:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:34:41 INFO - Return code: 0 19:34:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:34:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:34:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:34:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:34:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:34:41 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/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--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 19:34:41 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/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0358 --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 19:34:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iZdnQXnwyk/Render', 19:34:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:34:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:34:41 INFO - 'HOME': '/Users/cltbld', 19:34:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:34:41 INFO - 'LOGNAME': 'cltbld', 19:34:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:34:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:34:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:34:41 INFO - 'MOZ_NO_REMOTE': '1', 19:34:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:34:41 INFO - 'NO_EM_RESTART': '1', 19:34:41 INFO - 'PAGER': '/bin/cat', 19:34:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:34:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:34:41 INFO - 'PWD': '/builds/slave/test', 19:34:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:34:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:34:41 INFO - 'SHELL': '/bin/bash', 19:34:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hAOmQPISJ3/Listeners', 19:34:41 INFO - 'TMPDIR': '/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/', 19:34:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:34:41 INFO - 'USER': 'cltbld', 19:34:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:34:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:34:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:34:41 INFO - 'XPC_FLAGS': '0x0', 19:34:41 INFO - 'XPC_SERVICE_NAME': '0', 19:34:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:34:41 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/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0358', '--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 19:34:41 INFO - mozversion application_buildid: 20160928165820 19:34:41 INFO - mozversion application_changeset: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 19:34:41 INFO - mozversion application_display_name: Nightly 19:34:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:34:41 INFO - mozversion application_name: Firefox 19:34:41 INFO - mozversion application_remotingname: firefox 19:34:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 19:34:41 INFO - mozversion application_vendor: Mozilla 19:34:41 INFO - mozversion application_version: 52.0a1 19:34:41 INFO - mozversion platform_buildid: 20160928165820 19:34:41 INFO - mozversion platform_changeset: cde8b9afb57787e05bee1c891fa9d34bb5bf87a6 19:34:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 19:34:41 INFO - mozversion platform_version: 52.0a1 19:34:41 INFO - using testdate: 1475116481 19:34:41 INFO - actual date: 1475116481 19:34:41 INFO - starting webserver on 'localhost:49278' 19:34:41 INFO - SUITE-START | Running 2 tests 19:34:41 INFO - TEST-START | dromaeo_css 19:34:41 INFO - operating with platform_type : mac_ 19:34:41 INFO - Initialising browser for dromaeo_css test... 19:34:41 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpYvbpQK/profile http://localhost:49278/getInfo.html) 19:34:44 INFO - PROCESS | 1658 | 2016-09-28 19:34:44.494 plugin-container[1660:8496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c2f, name = 'com.apple.tsm.portname' 19:34:44 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:34:44 INFO - PROCESS | 1658 | 2016-09-28 19:34:44.496 plugin-container[1660:8496] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 19:34:44 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:34:44 INFO - PROCESS | 1658 | 2016-09-28 19:34:44.496 plugin-container[1660:8496] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:34:44 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 19:34:44 INFO - PROCESS | 1658 | colorDepth:24 19:34:44 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 19:34:44 INFO - PROCESS | 1658 | __metrics 19:34:44 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 19:34:45 INFO - TEST-INFO | 1658: exit 0 19:34:45 INFO - Browser initialized. 19:34:45 INFO - Running cycle 1/1 for dromaeo_css test... 19:34:45 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpYvbpQK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:34:47 INFO - PROCESS | 1662 | 2016-09-28 19:34:47.771 plugin-container[1663:8618] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x871b, name = 'com.apple.tsm.portname' 19:34:47 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:34:47 INFO - PROCESS | 1662 | 2016-09-28 19:34:47.774 plugin-container[1663:8618] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c13, name = 'com.apple.CFPasteboardClient' 19:34:47 INFO - PROCESS | 1662 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:34:47 INFO - PROCESS | 1662 | 2016-09-28 19:34:47.774 plugin-container[1663:8618] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:34:57 INFO - PROCESS | 1662 | RSS: Main: 240312320 19:34:57 INFO - PROCESS | 1662 | 19:35:47 INFO - PROCESS | 1662 | 1475116547141 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] 19:37:59 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49278/tests/dromaeo/cssquery-ext.html) 19:38:00 INFO - PROCESS | 1662 | RSS: Main: 253685760 19:38:00 INFO - PROCESS | 1662 | 19:41:02 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-ext.html (next: http://localhost:49278/tests/dromaeo/cssquery-jquery.html) 19:41:03 INFO - PROCESS | 1662 | RSS: Main: 266330112 19:41:03 INFO - PROCESS | 1662 | 19:44:05 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49278/tests/dromaeo/cssquery-mootools.html) 19:44:05 INFO - PROCESS | 1662 | RSS: Main: 270303232 19:44:05 INFO - PROCESS | 1662 | 19:47:07 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49278/tests/dromaeo/cssquery-prototype.html) 19:47:08 INFO - PROCESS | 1662 | RSS: Main: 271036416 19:47:08 INFO - PROCESS | 1662 | 19:50:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49278/tests/dromaeo/cssquery-yui.html) 19:50:11 INFO - PROCESS | 1662 | RSS: Main: 276574208 19:50:11 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49278/tests/dromaeo/cssquery-yui.html (next: http://localhost:49278/tests/dromaeo/cssquery-dojo.html) 19:53:14 INFO - PROCESS | 1662 | RSS: Main: 279908352 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | __start_tp_report 19:53:14 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 19:53:14 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 19:53:14 INFO - PROCESS | 1662 | |i|pagename|runs| 19:53:14 INFO - PROCESS | 1662 | |0;dojo.html;3402.8808991685282;3438.535797667315;3450.551428314322;3456.9308613827716;3464.722822174225;5682.914756278659;5749.3388260350075;5782.103773915044;5812.44781745734;5887.175795388649;5702.201691763146;5706.7431965561545;5722.7424765885535;5788.594798364116;5826.533877289818;5170.793168273264;5204.791808327663;5286.3392075990505;5322.308099947001;5331.706756128411;5271.578273738091;5405.702686352249;5421.593380496459;5437.755301011444;5482.150266708666;5191.091558977201;5224.656220019191;5256.658317209407;5283.524482796567;5290.761915713802;5232.3459567554055;5259.342582177228;5291.3121294231505;5321.361436627591;5364.758585863646;5188.92216616751;5203.375594928595;5211.218317252405;5266.052110620087;5295.895855017751;5628.915566266509;5643.661380317153;5663.688497132656;5770.134479828017;5784.82645520635;6162.599431036968;6177.042558403455;6203.8138855834395;6231.688415579203;6294.559380843343;6265.8120256392385;6271.310155882849;6277.8744425111245;6283.3088360280335;6298.622082675009;5854.092615644582;5916.26046744157;5935.643861368331;5936.139259807347;5978.222831031938;5277.472252774692;5709.914351284734;6139.416755408229;6240.251169859555;6240.344763799826;4784.6889952153;5197.7401129943355;5617.494425501643;5672.858178545487;6184.721687524072;6271.717772700239;6304.464120549805;6319.304876463585;6328.240611126695;6343;5143.7685304161405;5546.639468434539;6101.176341193882;6108.7861924833305;6231.40801623845;6051.304100028553;6195.1636529068;6256.311805701369;6308;6311.305756366796;4230.5769423057445;4994.775235114428;5000.074986127578;5004.699718016931;5027.245913112989;4399.49405818335;4418.513963464016;4429.712068715523;4448.7330760153845;4511.300748384006;5205.063088644005;5852;5906.911396329058;5910.940890591039;5921.259842519685;6185.567010309325;6225.906611400833;6257.74969001244;6271.05934109878;6278.183836101278;5746.281714785652;5814.3313518945015;5831.504322132666;5884.235049443545;5939;5421.024215641222;5473.972630136824;5494.692229263719;5502.944970550323;5523.226748255248;6377.649229292433;6405.839854003594;6414;6416.390442907917;6431.774887878902;6105.186826067751;6175.4750846176785;6250.593711408744;6259.718312676032;6268.498520118288;6307.90538141919;6310.968445157745;6341.207349081365;6416.871662566816;6442.581232219891;6241.188645476059;6262.655553944577;6263.65549894742;6272.560920735505;6277.748890044347;6112.837854638657;6262.93530099875;6266.624002559861;6268.905966410416;6272.027835685476;4776.21192503233;5702.51528620072;6101.9389806103145;6115.816525504242;6213.440790328892;4966.875828104182;5281.15501519755;6048.334683184934;6196.1015652731;6216.595921265103;5134.12719837621;5399.487048730364;5651.321841379061;5914.645121292736;6082.482988945989;6052.273727152769;6173.290071641639;6188.195534580475;6197.628142311295;6214.5028397727165;6041.2750469943885;6053.213082299273;6067.908981365372;6071.5446341523675;6082.78710245157 19:53:14 INFO - PROCESS | 1662 | |1;ext.html;519.5142541723487;529.203548659268;554.7586799742115;558.9329280486343;565.1353429253243;37878.0530486738;40771.59228407719;40859.18281634366;40985.79507102468;41001.589984100116;41622.375664365056;41980.160396792126;42055.57944420555;42061.78969105158;42120.15759684805;9509;9645.22838172948;9677.129058384744;9717.979612140729;9725.12473877351;9596.376235544667;9703.22374210065;9703.61185552577;9719.222462203043;9734.853977190347;8396.874046889301;8488.23605875471;9555;9683.564239609235;9702.805943881118;9617.23062155026;9646.131848133666;9691.12779849817;9702.07830256121;9716.562754676057;8318.251357377805;8812.1187881212;9104.543273076342;9577.712668619952;9591.328606997513;32976.010719678205;35850.924472265615;37014.44478332827;39418.0145496362;39586.60413395858;3312.1057314525183;3374.8481318340732;3453.033150717803;3456.2050728332374;3559.48387483816;24153.87923060391;26091.869540652177;29457.410851783057;29827.850860745773;30468.54297185544;3147.35479226758;3284.556584861037;3379.8648054078053;3384.2554637979606;3384.509246159293;492647;497379;499750;500214.4989275067;500806;59649;59972.200833975054;63337;65413.34586654072;65447;52414.737926310125;63809;67340.6632966832;67399.66300168468;68161.3183868155;15913.443029493912;16151.03093814375;16376.344946202256;16381.754273685905;16392.26234819436;12419;12481.937590312173;12621.116521843383;12627.368631568384;12635.115541911979;19577.314793982427;19630.41108766739;19651.901740491205;20019.29932452386;20047.699284510745;7914.366850651934;8263.173682631808;8265.148523759293;8300.335973122135;8313.916860831436;806.6491076381793;828.2173346187915;947.2706016367496;994.4828689081781;1073.0771536478778;29583.704162958526;29921;29954.850225749167;30630.387392252476;30927.381452370828;1029.25893356783;1063.8297872340543;1104.9116070714324;1149.2357582207737;1174.2836869509592;14881.776773348409;15338;15665.765013524806;15824.76262856058;15938.043717376995;673307.6334618295;674541;679128;679505;681238;40734.38898416526;42102.15795684069;42280.57719422828;42421.78789106035;42511.93720157021;1125.3682160866776;1344.3143996561823;1392.1577445645441;1396.6787638843123;1398.5524632117829;28852;29762.404751905273;29864.104076877727;30420.695793042672;30679.693203067683;50451.243231352295;50975.74512127416;51427;51498.74250628723;51666.48333516767;581083;591435;593572;594369;596989;26878.731212687624;27613;27633.171004869888;42465;43063.56936430596;39678;46617.76691116523;46977.2953405706;47306.29040564463;47901.28148077851;38688;39148.608513915635;39299;39790.403143953445;40262;33242.66757332492;38745.806270969595;39073.023174420865;40042.59957400505;40461.797691012536;31183.688163118077;37473.62526374702;37846;37894.24211515809;38344;25634.487310254062;32172.83913580417;34241.82879085688;34630;34944.65055349414;24199.51600968006;25357.85889634934;25424.491510170064;25560.744392555836;26092 19:53:14 INFO - PROCESS | 1662 | |2;jquery.html;328.8883573521497;348.747158310225;359.0700086775253;370.2262271851829;391.27613914258626;18467.076646167705;18641.347552835654;18673.90663046683;18896;19404.805951940492;19261.903690481562;19906.90046549769;20003.499912502193;20481.487962800937;20594.588108237866;5323.334583177103;5407.945920540794;5597.888042239173;5611.186377975191;5624.746886390102;5438.91841622376;5496.258005169291;5509.311336082989;5518.586106042043;5534.252875861747;5276.182191760283;5413.377461591928;5490.149026900816;5491.972540137293;5494.340679118512;5388.488093631099;5518.668879867221;5523.7238138093335;5528.834134975958;5540.833774986737;5097.388313402378;5295.285136506561;5296.814611488618;5326.493983071602;5356.54469370104;13115.803262951064;13267.402966866513;13271.270080145589;13272.800907986388;16758.832411675972;1929.5947850951318;2142.828573714099;2297.689811875392;2331.6269396896414;2709.24141240453;7295;8797.868031979526;8970.923489181274;9034.45793252407;10326.896731032668;1839.0988415676222;1865.6921607934673;1892.1579896945864;1912.368918256972;1931.2082046360924;123115;125850;128652;129115;131026.68973310335;9257.166854983085;9920.40477571348;9958.30291879576;9984.60061597528;11284.943575282236;10375.16998640107;10748.838767418494;11810.232334898204;12420.01919539304;12448.937755311166;7983.72056978003;8077.798055048671;8099.230573095547;8112.107668156498;8184.386171037196;8418.368622353348;8424.073351931283;8510.914890851136;8604.526751028628;8658.826823463622;3835.4055121456217;3839.9424008639894;3888.2612303662213;3903;3925.3130702127014;1683.8989660620402;1929.0933261367136;1971.2312198242696;1974.1215159254282;1977.2600111901722;868.522312727997;869.5043825019804;879.8284334554701;961.2166084641135;998.8401855703053;15209.771853422208;19862;20678.379648610266;20720.689189662167;20866.165353385695;920.7283851263893;942.2650332740474;969.5103972493846;984.9064338887795;995.1590905684685;10115.797684046278;10472.162227021972;11251.268667536584;11262.774744505228;11266.380349080715;556270;575464;577955;586199;587935;125767;126979;136184.31907840597;141541;141756;928.2342067794096;971.5385192033868;972.2659392158874;978;994.7115336552318;18291.26834926641;19465.4160375189;19510.31713890007;19563.60872782565;19714.60570788605;53150.20274695805;54728.45271547233;54969;55331.893362133334;55903;85585.57207213924;85608;85948.57025714831;86505.56747216223;86981;7422.7381345172535;7982.760517184493;8003.799905002422;8181.754547363588;8298.958505207434;10856.891431085589;12729.236245825281;12805.039622028571;12998.155119917175;13066.281354525212;9114.544272786201;11111.499982500645;11258.549658013915;11322.490487927898;11540.711482213012;10138.594456221668;10449.425281609585;10626.256162068581;10971.286866353661;11392.316461012366;8332.458390204618;10050.743657042869;11423.030012196723;11440.82838757436;11537.538498460304;7251.311125443074;8008.879866802119;8140.959295203486;8183.3862460316905;8210.63052162642;10870.565177392815;11234.213605047567;11283.435828208721;11297.43512824372;11323.547058117912 19:53:14 INFO - PROCESS | 1662 | |3;mootools.html;1525.4432132271718;1569.270289315468;1805.295934585512;1850.5096149520389;1921.5868588253531;7906.2489063539015;8089.433739638213;8539.188777066185;9117.452952822827;9123.361364704473;7220.422366210703;7882.369410447151;8839.248663863564;8945.418547794388;8951.149640784131;2354.3643216331625;2401.387646150238;2401.543706695731;2402.411409204747;2402.6396040593963;2358.84667496613;2377.3462297868136;2394.4851856850773;2400.099962514057;2402.6876506054277;2113.8479528656917;2335.4044718596733;2380.8928598213033;2403.903843846244;2404.6393041043807;1962.3524237001827;2321.303608917318;2334.6964894563685;2340.110757911991;2377.976220237793;1998.5003749062735;2000.7499062617173;2361.327021798796;2363.6218205087102;2370.431096536836;7273.599952002638;8762.561871906442;8910.50992195429;8922.776930576723;8993.865092023625;4634;4736.9526304736855;5363.704996225206;5437.293151890269;5438.048341540215;3081.7534597232393;3520.1375662962737;3646.08847788053;3672.2839046385966;3703.666669999713;1048.8187273611945;1066.7653116314398;1081.9513121909533;1088.4775307852196;1090.1278976818592;11694.883051169465;11798.292102473879;11859.644210673694;11866.644000679908;11878.168528202943;4550.795214215368;4651.86044418668;4773.785179666923;4801.783919723621;4815.398075240595;8566.14338566151;10003.749906252402;10057.899421005697;10092.949535252423;10122.645707400354;5350.38470575881;5382.811601594004;5390.784368625289;5395.433479484597;5396.622236443412;4400.317950717644;4563.748993805306;4746.43042834862;4884.511548845087;4928.211486162197;3264.281857991238;3896.922061558753;3974.900627484278;3975.860844870416;4006.9198616027516;1287.750881644803;1297.4939773488347;1297.8247936528637;1305.8824705776526;1309.9476020959055;773.7756050745334;852.855014647499;908.7092130518171;910.988802429303;923.5659240157115;4767.736549814302;5343.465653434625;5354.571634269249;5795.681237531976;5922.733476993546;820.6471217376585;908.6728777640044;931.9953400232955;933.7198840347869;970.45169479244;3935.5080614923136;3977.721559490808;4026.1479719329436;4075.775832329191;4087.6525495332635;10226.948865255627;10531.473426328654;10911.399873007062;10975.670729878117;11001.559937602406;10089.899101009043;10644.285707144783;10660.680179594625;10830.78338433227;11101.722456938642;7345.853082938418;8096.716614918568;8298.174401631752;8304.377171712025;8312.501249925024;5548.611597188158;5878.265216847894;5950.910736338872;6057.757689692539;6202.224721909762;8557.272631826116;10134.79730405373;10210.132138768287;10230.181585472996;10231.7442063949;10100.797984040131;10185;10219.1824654026;10226.948865255627;10268.229882758673;6531.216254049545;6570.868582628416;7248.710051598088;7444.255574442729;7623.390128789794;8237.341012719087;8479.109693482096;8488.660453581788;8492.065872754116;8560.400771946153;7436.888446673413;7546.509476883985;7630;7633.580153091633;7706.730264440942;7321.341079302888;7336.339729424378;7349.8162545936775;7490.962545187239;7518.962405187939;6820.35206655367;6973.12835895513;6983.930160698328;6988.685509151998;7001.054857594363;4754.928676069931;4933.432655244693;5077.162268225849;5104.3619547556555;5123.923141152811;4698.7415692137265;4885.95114048855;4988.825391111293;5010.749462526932;5107.642465027412 19:53:14 INFO - PROCESS | 1662 | |4;prototype.html;277.38282321833907;287.57008272632413;307.60780005493;313.0483330674749;314.50622522639435;5343.625946183769;5488.423715509874;5545.3363990802745;5619.634723742959;5646.20953066571;5328.520433161015;5393.973030134844;5504.284443022401;5521.475459831309;5540.556755459564;4215.704900656955;4215.810288537008;4218.261804184271;4255.382969469425;4258.44640196774;3911.6334617556395;4101.405296232045;4239.788010599473;4242.9151416971645;4271.78641067947;4107.671386289105;4166.145940082276;4176.770277634729;4204.369344598319;4211.831526738927;3526.894193174209;4022.3966405039187;4194.2869712149;4222.936655950163;4229.196452673982;3688.2254726507467;3694.612065733086;3698.2418604186078;3699.278640665071;3702.2595480903656;4625.560571745679;4654.837080702159;5000.624953128494;5041.294218809369;5298.178782288713;4066.9186616267507;4801.375821143264;4803.0874133914795;4826.589739872115;4831.541003604652;5028.949710502921;5523.8066667666435;5535.916961245584;5768.826935191951;5790.826275211751;3443.500692399588;3762.3792074307707;3970.9602903971168;4003.83984640614;4034.334334834749;5074.640292264115;6031.4270144336215;6122.081687746829;6125.5712100153005;6171.809007802255;3432.4851272308856;3892.8832135035996;4075.2053349596545;4079.3065178919655;4116.341385378325;2877.4101309231555;3965.4448377227213;3989.8005099744896;4014.959850401517;4213.705040647125;4690.742009189459;4694.600958918462;4713.387259656222;4721.551452611996;4725.598324142417;4858.562729354305;5695.117256825199;5817.418258174233;5825.38833422493;5848.590598658053;1173.7652469506133;1209.38925842449;1293.489071816627;1350.8041334006514;1363.1548439967085;736.3887972982412;809.2393150438569;836.7071524966335;842.8145807922248;847.5719761520515;3839.404892241651;4120.958790412057;4452.4211852458975;4485.820567177277;4568.657350698711;4585.977070114628;4638.97680511602;5038.961038961039;5070.6957582545165;5099.719515426613;4179.077624292799;4581.5647513486165;4589.3478347795435;4620.435658209726;4630.421197350332;2391.09138527362;2400.9867616141632;2423.551642946061;2480.5860659424316;2651.7613414792763;4273.465816772903;5122.9743851281255;5173.63784535079;5197.532222100029;5199.350081239845;4671.318325402853;4893.266010098514;4922.778474968635;4943.728094954812;5123.38519377677;4100.671946244293;4217.768022758716;4850.805967761321;5156.2523434102595;5163.251328557413;4181.519125300508;4722.952770472251;4731.361266229015;4843.878903027453;5014.648974571745;4450.488193857748;4731.6751309633355;4821.421429428491;4915.8033678652455;4956.950430495649;4816.975915120402;4994.900101997867;5042.697438153723;5184;5187.170052791535;2981.850907454575;3075.0467355119986;3152.5271209318785;3225.8387080646344;3319.9708090492027;4870.805167793249;5000.67495612784;5025.422076461254;5055.823046193365;5068.72122033277;4107.753534787922;5591.245181900392;5731.251968149754;5777.566682498914;5828.735907891594;5151.768170432265;5580.972095139498;5771.5671324649975;5806.200077975841;5835.328937172279;5093;5280.419153892992;5361.329833770778;5382.896506216138;5631.1271752878365;5048.798048078036;5312.7061222042585;5598.076317407745;5722.768120432951;5811.094452773613;5001.674891132065;5438.70087145195;5693.4875861172695;5706.828795136153;5747.48272655463 19:53:14 INFO - PROCESS | 1662 | |5;yui.html;604.9485793707535;681.9488538359618;712.8146681862714;738.8116030412244;789.8973133492643;14851.480198193065;15223.086614803105;15299.464518741846;15301.617459563517;15388.538343849688;14606.269686515685;14726.926365368185;14738.55784326472;14749.188794616291;14760.33578488968;664.9401553860151;684.7226873116396;811.7524155132683;814.4136221920209;837.3803385494751;821.6754382019099;829.8381815545971;843.2537204573965;843.7637461510786;843.8734189871507;890.7773056735816;891.8528442806961;893.8927328720563;893.9821203575924;894.2667013049302;881.2113158722971;881.894172699277;887.5118684723441;888.3825741109931;890.0254221627308;657.3557913245047;657.730330564471;658.6114192626326;659.910912026875;660.7324033766332;15443.691126177413;15528.223588820627;15545;15557.766633500507;15578.29897654597;10900.563977440894;14488.637784055378;14751.483698070517;14904.850951490454;15095.169765662884;11994.520219191309;12041.638750837488;12043.578474753342;12047.518099276018;12126.818097728541;3315.9171020724434;3852.210296889131;3859.7298189126773;3994.960050399488;4015.7791321477307;107500.31249218754;121095;123968;124572.37713811464;125590;9486.335956483117;10907.805091836259;11416.37209953444;11502.194846360675;11899;23137.421564460685;23229.41926451852;23496.647550286758;23515.294541163792;23671.763282366956;8177.1414001530175;8192.221738934792;8240.21717936795;8302.792430189294;8345.123762005023;4537.954620453819;4603;4648.279516674921;4702.6473014524045;4719.2685133803625;3543.94684079739;3642.21692336149;3645.8906232813056;3646.927061458809;3655.049687081378;635.7361694896566;647.5564238496551;655.7639249870043;656.5601047298226;661.2362721057186;614.4163045107166;651.2673242602073;652.6997581112646;655.6951017776756;664.6809531424847;8793.912060879436;9029.898107569203;9099.636014559475;9492.236426171396;9854.605815767432;639.785671799943;641.2913730328014;642.1651852591613;683.1597135523334;686.7150132694976;3930.8820735377985;3984.521857377133;4061.147159096623;4498.640108791289;4502.514170323785;119516;123909;126032;131458;150272.2486387561;101176;129268.70731292755;131520;132541.67458325485;133133.66866331213;56859.43140568624;72845.27154728491;111003;111347.44326278317;112472;9716.125548700367;9784.630688883362;10016;10035.117959691248;10092.697219083439;61198;62359.6882015587;64478.677606611665;65662.67168664126;66656.00031999047;39876.80061599673;68762;78772.60613696894;81953.18046819694;93580;13250.867491325349;13254.271015094262;13392.196468211938;13440.92472602209;13461.461541538229;10621;12092.334921579397;14749.778753318924;15280.223404941244;15688.529344119695;14100.435982560582;14144.292785360485;14185.716285674434;14240.715185696436;14330.283485825876;13729.725405491634;13813.654658633615;13966.4413423466;14113.29433528299;14582.270886455422;13573.1856088635;13770.934359846104;13788.793168102688;13943.233122178377;14056.929715351358;9632.95183524078;10935.781284374109;11315.037874084192;11436.885631143583;11442.885571144516;9359.438433694;9586.712398628053;10355.223358248311;10866.402347870942;11495.885041149482 19:53:14 INFO - PROCESS | 1662 | __end_tp_report 19:53:14 INFO - PROCESS | 1662 | __start_cc_report 19:53:14 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,362 19:53:14 INFO - PROCESS | 1662 | __end_cc_report 19:53:14 INFO - PROCESS | 1662 | __startTimestamp1475117594865__endTimestamp 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | ------- Summary: start ------- 19:53:14 INFO - PROCESS | 1662 | Number of tests: 6 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5721.42 Median:5906.91 stddev:645.09 (10.9%) stddev-sans-first:620.89 19:53:14 INFO - PROCESS | 1662 | Values: 3402.9 3438.5 3450.6 3456.9 3464.7 5682.9 5749.3 5782.1 5812.4 5887.2 5702.2 5706.7 5722.7 5788.6 5826.5 5170.8 5204.8 5286.3 5322.3 5331.7 5271.6 5405.7 5421.6 5437.8 5482.2 5191.1 5224.7 5256.7 5283.5 5290.8 5232.3 5259.3 5291.3 5321.4 5364.8 5188.9 5203.4 5211.2 5266.1 5295.9 5628.9 5643.7 5663.7 5770.1 5784.8 6162.6 6177.0 6203.8 6231.7 6294.6 6265.8 6271.3 6277.9 6283.3 6298.6 5854.1 5916.3 5935.6 5936.1 5978.2 5277.5 5709.9 6139.4 6240.3 6240.3 4784.7 5197.7 5617.5 5672.9 6184.7 6271.7 6304.5 6319.3 6328.2 6343.0 5143.8 5546.6 6101.2 6108.8 6231.4 6051.3 6195.2 6256.3 6308.0 6311.3 4230.6 4994.8 5000.1 5004.7 5027.2 4399.5 4418.5 4429.7 4448.7 4511.3 5205.1 5852.0 5906.9 5910.9 5921.3 6185.6 6225.9 6257.7 6271.1 6278.2 5746.3 5814.3 5831.5 5884.2 5939.0 5421.0 5474.0 5494.7 5502.9 5523.2 6377.6 6405.8 6414.0 6416.4 6431.8 6105.2 6175.5 6250.6 6259.7 6268.5 6307.9 6311.0 6341.2 6416.9 6442.6 6241.2 6262.7 6263.7 6272.6 6277.7 6112.8 6262.9 6266.6 6268.9 6272.0 4776.2 5702.5 6101.9 6115.8 6213.4 4966.9 5281.2 6048.3 6196.1 6216.6 5134.1 5399.5 5651.3 5914.6 6082.5 6052.3 6173.3 6188.2 6197.6 6214.5 6041.3 6053.2 6067.9 6071.5 6082.8 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:71549.46 Median:40598.09 stddev:158870.63 (391.3%) stddev-sans-first:159226.79 19:53:14 INFO - PROCESS | 1662 | Values: 519.5 529.2 554.8 558.9 565.1 37878.1 40771.6 40859.2 40985.8 41001.6 41622.4 41980.2 42055.6 42061.8 42120.2 9509.0 9645.2 9677.1 9718.0 9725.1 9596.4 9703.2 9703.6 9719.2 9734.9 8396.9 8488.2 9555.0 9683.6 9702.8 9617.2 9646.1 9691.1 9702.1 9716.6 8318.3 8812.1 9104.5 9577.7 9591.3 32976.0 35850.9 37014.4 39418.0 39586.6 3312.1 3374.8 3453.0 3456.2 3559.5 24153.9 26091.9 29457.4 29827.9 30468.5 3147.4 3284.6 3379.9 3384.3 3384.5 492647.0 497379.0 499750.0 500214.5 500806.0 59649.0 59972.2 63337.0 65413.3 65447.0 52414.7 63809.0 67340.7 67399.7 68161.3 15913.4 16151.0 16376.3 16381.8 16392.3 12419.0 12481.9 12621.1 12627.4 12635.1 19577.3 19630.4 19651.9 20019.3 20047.7 7914.4 8263.2 8265.1 8300.3 8313.9 806.6 828.2 947.3 994.5 1073.1 29583.7 29921.0 29954.9 30630.4 30927.4 1029.3 1063.8 1104.9 1149.2 1174.3 14881.8 15338.0 15665.8 15824.8 15938.0 673307.6 674541.0 679128.0 679505.0 681238.0 40734.4 42102.2 42280.6 42421.8 42511.9 1125.4 1344.3 1392.2 1396.7 1398.6 28852.0 29762.4 29864.1 30420.7 30679.7 50451.2 50975.7 51427.0 51498.7 51666.5 581083.0 591435.0 593572.0 594369.0 596989.0 26878.7 27613.0 27633.2 42465.0 43063.6 39678.0 46617.8 46977.3 47306.3 47901.3 38688.0 39148.6 39299.0 39790.4 40262.0 33242.7 38745.8 39073.0 40042.6 40461.8 31183.7 37473.6 37846.0 37894.2 38344.0 25634.5 32172.8 34241.8 34630.0 34944.7 24199.5 25357.9 25424.5 25560.7 26092.0 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:34484.84 Median:3864.10 stddev:97168.67 (2514.7%) stddev-sans-first:97407.41 19:53:14 INFO - PROCESS | 1662 | Values: 328.9 348.7 359.1 370.2 391.3 18467.1 18641.3 18673.9 18896.0 19404.8 19261.9 19906.9 20003.5 20481.5 20594.6 5323.3 5407.9 5597.9 5611.2 5624.7 5438.9 5496.3 5509.3 5518.6 5534.3 5276.2 5413.4 5490.1 5492.0 5494.3 5388.5 5518.7 5523.7 5528.8 5540.8 5097.4 5295.3 5296.8 5326.5 5356.5 13115.8 13267.4 13271.3 13272.8 16758.8 1929.6 2142.8 2297.7 2331.6 2709.2 7295.0 8797.9 8970.9 9034.5 10326.9 1839.1 1865.7 1892.2 1912.4 1931.2 123115.0 125850.0 128652.0 129115.0 131026.7 9257.2 9920.4 9958.3 9984.6 11284.9 10375.2 10748.8 11810.2 12420.0 12448.9 7983.7 8077.8 8099.2 8112.1 8184.4 8418.4 8424.1 8510.9 8604.5 8658.8 3835.4 3839.9 3888.3 3903.0 3925.3 1683.9 1929.1 1971.2 1974.1 1977.3 868.5 869.5 879.8 961.2 998.8 15209.8 19862.0 20678.4 20720.7 20866.2 920.7 942.3 969.5 984.9 995.2 10115.8 10472.2 11251.3 11262.8 11266.4 556270.0 575464.0 577955.0 586199.0 587935.0 125767.0 126979.0 136184.3 141541.0 141756.0 928.2 971.5 972.3 978.0 994.7 18291.3 19465.4 19510.3 19563.6 19714.6 53150.2 54728.5 54969.0 55331.9 55903.0 85585.6 85608.0 85948.6 86505.6 86981.0 7422.7 7982.8 8003.8 8181.8 8299.0 10856.9 12729.2 12805.0 12998.2 13066.3 9114.5 11111.5 11258.5 11322.5 11540.7 10138.6 10449.4 10626.3 10971.3 11392.3 8332.5 10050.7 11423.0 11440.8 11537.5 7251.3 8008.9 8141.0 8183.4 8210.6 10870.6 11234.2 11283.4 11297.4 11323.5 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5672.08 Median:4750.68 stddev:3197.46 (67.3%) stddev-sans-first:3191.25 19:53:14 INFO - PROCESS | 1662 | Values: 1525.4 1569.3 1805.3 1850.5 1921.6 7906.2 8089.4 8539.2 9117.5 9123.4 7220.4 7882.4 8839.2 8945.4 8951.1 2354.4 2401.4 2401.5 2402.4 2402.6 2358.8 2377.3 2394.5 2400.1 2402.7 2113.8 2335.4 2380.9 2403.9 2404.6 1962.4 2321.3 2334.7 2340.1 2378.0 1998.5 2000.7 2361.3 2363.6 2370.4 7273.6 8762.6 8910.5 8922.8 8993.9 4634.0 4737.0 5363.7 5437.3 5438.0 3081.8 3520.1 3646.1 3672.3 3703.7 1048.8 1066.8 1082.0 1088.5 1090.1 11694.9 11798.3 11859.6 11866.6 11878.2 4550.8 4651.9 4773.8 4801.8 4815.4 8566.1 10003.7 10057.9 10092.9 10122.6 5350.4 5382.8 5390.8 5395.4 5396.6 4400.3 4563.7 4746.4 4884.5 4928.2 3264.3 3896.9 3974.9 3975.9 4006.9 1287.8 1297.5 1297.8 1305.9 1309.9 773.8 852.9 908.7 911.0 923.6 4767.7 5343.5 5354.6 5795.7 5922.7 820.6 908.7 932.0 933.7 970.5 3935.5 3977.7 4026.1 4075.8 4087.7 10226.9 10531.5 10911.4 10975.7 11001.6 10089.9 10644.3 10660.7 10830.8 11101.7 7345.9 8096.7 8298.2 8304.4 8312.5 5548.6 5878.3 5950.9 6057.8 6202.2 8557.3 10134.8 10210.1 10230.2 10231.7 10100.8 10185.0 10219.2 10226.9 10268.2 6531.2 6570.9 7248.7 7444.3 7623.4 8237.3 8479.1 8488.7 8492.1 8560.4 7436.9 7546.5 7630.0 7633.6 7706.7 7321.3 7336.3 7349.8 7491.0 7519.0 6820.4 6973.1 6983.9 6988.7 7001.1 4754.9 4933.4 5077.2 5104.4 5123.9 4698.7 4886.0 4988.8 5010.7 5107.6 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4373.36 Median:4802.23 stddev:1341.00 (27.9%) stddev-sans-first:1309.04 19:53:14 INFO - PROCESS | 1662 | Values: 277.4 287.6 307.6 313.0 314.5 5343.6 5488.4 5545.3 5619.6 5646.2 5328.5 5394.0 5504.3 5521.5 5540.6 4215.7 4215.8 4218.3 4255.4 4258.4 3911.6 4101.4 4239.8 4242.9 4271.8 4107.7 4166.1 4176.8 4204.4 4211.8 3526.9 4022.4 4194.3 4222.9 4229.2 3688.2 3694.6 3698.2 3699.3 3702.3 4625.6 4654.8 5000.6 5041.3 5298.2 4066.9 4801.4 4803.1 4826.6 4831.5 5028.9 5523.8 5535.9 5768.8 5790.8 3443.5 3762.4 3971.0 4003.8 4034.3 5074.6 6031.4 6122.1 6125.6 6171.8 3432.5 3892.9 4075.2 4079.3 4116.3 2877.4 3965.4 3989.8 4015.0 4213.7 4690.7 4694.6 4713.4 4721.6 4725.6 4858.6 5695.1 5817.4 5825.4 5848.6 1173.8 1209.4 1293.5 1350.8 1363.2 736.4 809.2 836.7 842.8 847.6 3839.4 4121.0 4452.4 4485.8 4568.7 4586.0 4639.0 5039.0 5070.7 5099.7 4179.1 4581.6 4589.3 4620.4 4630.4 2391.1 2401.0 2423.6 2480.6 2651.8 4273.5 5123.0 5173.6 5197.5 5199.4 4671.3 4893.3 4922.8 4943.7 5123.4 4100.7 4217.8 4850.8 5156.3 5163.3 4181.5 4723.0 4731.4 4843.9 5014.6 4450.5 4731.7 4821.4 4915.8 4957.0 4817.0 4994.9 5042.7 5184.0 5187.2 2981.9 3075.0 3152.5 3225.8 3320.0 4870.8 5000.7 5025.4 5055.8 5068.7 4107.8 5591.2 5731.3 5777.6 5828.7 5151.8 5581.0 5771.6 5806.2 5835.3 5093.0 5280.4 5361.3 5382.9 5631.1 5048.8 5312.7 5598.1 5722.8 5811.1 5001.7 5438.7 5693.5 5706.8 5747.5 19:53:14 INFO - PROCESS | 1662 | 19:53:14 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:23685.38 Median:3646.41 stddev:37173.63 (1019.5%) stddev-sans-first:37237.52 19:53:14 INFO - PROCESS | 1662 | Values: 604.9 681.9 712.8 738.8 789.9 14851.5 15223.1 15299.5 15301.6 15388.5 14606.3 14726.9 14738.6 14749.2 14760.3 664.9 684.7 811.8 814.4 837.4 821.7 829.8 843.3 843.8 843.9 890.8 891.9 893.9 894.0 894.3 881.2 881.9 887.5 888.4 890.0 657.4 657.7 658.6 659.9 660.7 15443.7 15528.2 15545.0 15557.8 15578.3 10900.6 14488.6 14751.5 14904.9 15095.2 11994.5 12041.6 12043.6 12047.5 12126.8 3315.9 3852.2 3859.7 3995.0 4015.8 107500.3 121095.0 123968.0 124572.4 125590.0 9486.3 10907.8 11416.4 11502.2 11899.0 23137.4 23229.4 23496.6 23515.3 23671.8 8177.1 8192.2 8240.2 8302.8 8345.1 4538.0 4603.0 4648.3 4702.6 4719.3 3543.9 3642.2 3645.9 3646.9 3655.0 635.7 647.6 655.8 656.6 661.2 614.4 651.3 652.7 655.7 664.7 8793.9 9029.9 9099.6 9492.2 9854.6 639.8 641.3 642.2 683.2 686.7 3930.9 3984.5 4061.1 4498.6 4502.5 119516.0 123909.0 126032.0 131458.0 150272.2 101176.0 129268.7 131520.0 132541.7 133133.7 56859.4 72845.3 111003.0 111347.4 112472.0 9716.1 9784.6 10016.0 10035.1 10092.7 61198.0 62359.7 64478.7 65662.7 66656.0 39876.8 68762.0 78772.6 81953.2 93580.0 13250.9 13254.3 13392.2 13440.9 13461.5 10621.0 12092.3 14749.8 15280.2 15688.5 14100.4 14144.3 14185.7 14240.7 14330.3 13729.7 13813.7 13966.4 14113.3 14582.3 13573.2 13770.9 13788.8 13943.2 14056.9 9633.0 10935.8 11315.0 11436.9 11442.9 9359.4 9586.7 10355.2 10866.4 11495.9 19:53:14 INFO - PROCESS | 1662 | -------- Summary: end -------- 19:53:14 INFO - PROCESS | 1662 | 19:53:15 INFO - TEST-INFO | 1662: exit 0 19:53:15 INFO - TEST-OK | dromaeo_css | took 1113762ms 19:53:15 INFO - TEST-START | kraken 19:53:15 INFO - operating with platform_type : mac_ 19:53:15 INFO - Initialising browser for kraken test... 19:53:15 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpTJlIl4/profile http://localhost:49278/getInfo.html) 19:53:18 INFO - PROCESS | 1670 | 2016-09-28 19:53:18.204 plugin-container[1671:10646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a1f, name = 'com.apple.tsm.portname' 19:53:18 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:53:18 INFO - PROCESS | 1670 | 2016-09-28 19:53:18.206 plugin-container[1671:10646] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 19:53:18 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:53:18 INFO - PROCESS | 1670 | 2016-09-28 19:53:18.206 plugin-container[1671:10646] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:53:18 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 19:53:18 INFO - PROCESS | 1670 | colorDepth:24 19:53:18 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 19:53:18 INFO - PROCESS | 1670 | __metrics 19:53:18 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3076: TypeError: this.tabs is undefined 19:53:19 INFO - TEST-INFO | 1670: exit 0 19:53:19 INFO - Browser initialized. 19:53:19 INFO - Running cycle 1/1 for kraken test... 19:53:19 INFO - TEST-INFO | started process 1672 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/T/tmpTJlIl4/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:53:21 INFO - PROCESS | 1672 | 2016-09-28 19:53:21.448 plugin-container[1673:10753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x441f, name = 'com.apple.tsm.portname' 19:53:21 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:53:21 INFO - PROCESS | 1672 | 2016-09-28 19:53:21.450 plugin-container[1673:10753] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 19:53:21 INFO - PROCESS | 1672 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:53:21 INFO - PROCESS | 1672 | 2016-09-28 19:53:21.450 plugin-container[1673:10753] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:53:31 INFO - PROCESS | 1672 | RSS: Main: 240160768 19:53:31 INFO - PROCESS | 1672 | 19:53:34 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection) 19:53:35 INFO - PROCESS | 1672 | RSS: Main: 253886464 19:53:35 INFO - PROCESS | 1672 | 19:53:37 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-dft) 19:53:38 INFO - PROCESS | 1672 | RSS: Main: 261517312 19:53:38 INFO - PROCESS | 1672 | 19:53:41 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-fft) 19:53:42 INFO - PROCESS | 1672 | RSS: Main: 262254592 19:53:42 INFO - PROCESS | 1672 | 19:53:44 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator) 19:53:45 INFO - PROCESS | 1672 | RSS: Main: 263917568 19:53:45 INFO - PROCESS | 1672 | 19:53:47 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:53:47 INFO - PROCESS | 1672 | RSS: Main: 263831552 19:53:47 INFO - PROCESS | 1672 | 19:53:50 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom) 19:53:51 INFO - PROCESS | 1672 | RSS: Main: 265707520 19:53:51 INFO - PROCESS | 1672 | 19:53:54 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate) 19:53:54 INFO - PROCESS | 1672 | RSS: Main: 265924608 19:53:54 INFO - PROCESS | 1672 | 19:53:57 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial) 19:53:58 INFO - PROCESS | 1672 | RSS: Main: 266285056 19:53:58 INFO - PROCESS | 1672 | 19:53:59 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:54:00 INFO - PROCESS | 1672 | RSS: Main: 265809920 19:54:00 INFO - PROCESS | 1672 | 19:54:02 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:54:03 INFO - PROCESS | 1672 | RSS: Main: 265875456 19:54:03 INFO - PROCESS | 1672 | 19:54:05 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:54:06 INFO - PROCESS | 1672 | RSS: Main: 265916416 19:54:06 INFO - PROCESS | 1672 | 19:54:09 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:54:09 INFO - PROCESS | 1672 | RSS: Main: 266244096 19:54:09 INFO - PROCESS | 1672 | 19:54:12 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:54:13 INFO - PROCESS | 1672 | RSS: Main: 266264576 19:54:13 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | Cycle 1(1): loaded http://localhost:49278/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49278/tests/kraken/driver.html?testName=ai-astar) 19:54:15 INFO - PROCESS | 1672 | RSS: Main: 267653120 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | __start_tp_report 19:54:15 INFO - PROCESS | 1672 | _x_x_mozilla_page_load 19:54:15 INFO - PROCESS | 1672 | _x_x_mozilla_page_load_details 19:54:15 INFO - PROCESS | 1672 | |i|pagename|runs| 19:54:15 INFO - PROCESS | 1672 | |0;ai-astar;115;159;119;117;118;119;117;118;118;118 19:54:15 INFO - PROCESS | 1672 | |1;audio-beat-detection;142;122;108;145;111;112;114;109;150;138 19:54:15 INFO - PROCESS | 1672 | |2;audio-dft;259;266;277;277;227;200;195;195;190;192 19:54:15 INFO - PROCESS | 1672 | |3;audio-fft;82;65;83;75;78;83;82;85;78;60 19:54:15 INFO - PROCESS | 1672 | |4;audio-oscillator;83;71;87;58;85;103;72;93;58;89 19:54:15 INFO - PROCESS | 1672 | |5;imaging-gaussian-blur;90;88;91;89;91;87;93;87;90;88 19:54:15 INFO - PROCESS | 1672 | |6;imaging-darkroom;101;101;104;100;103;101;101;109;174;115 19:54:15 INFO - PROCESS | 1672 | |7;imaging-desaturate;91;93;135;89;94;91;91;91;91;91 19:54:15 INFO - PROCESS | 1672 | |8;json-parse-financial;50;64;74;52;58;64;52;64;88;88 19:54:15 INFO - PROCESS | 1672 | |9;json-stringify-tinderbox;68;77;83;109;69;67;86;103;105;60 19:54:15 INFO - PROCESS | 1672 | |10;stanford-crypto-aes;120;76;99;105;106;106;88;93;88;84 19:54:15 INFO - PROCESS | 1672 | |11;stanford-crypto-ccm;142;145;137;138;136;121;161;164;206;143 19:54:15 INFO - PROCESS | 1672 | |12;stanford-crypto-pbkdf2;175;174;173;171;169;179;244;208;187;237 19:54:15 INFO - PROCESS | 1672 | |13;stanford-crypto-sha256-iterative;73;91;92;85;69;67;68;71;69;68 19:54:15 INFO - PROCESS | 1672 | __end_tp_report 19:54:15 INFO - PROCESS | 1672 | __start_cc_report 19:54:15 INFO - PROCESS | 1672 | _x_x_mozilla_cycle_collect,912 19:54:15 INFO - PROCESS | 1672 | __end_cc_report 19:54:15 INFO - PROCESS | 1672 | __startTimestamp1475117655510__endTimestamp 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | ------- Summary: start ------- 19:54:15 INFO - PROCESS | 1672 | Number of tests: 14 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#0] ai-astar Cycles:10 Average:121.80 Median:118.00 stddev:13.12 (11.1%) stddev-sans-first:13.68 19:54:15 INFO - PROCESS | 1672 | Values: 115.0 159.0 119.0 117.0 118.0 119.0 117.0 118.0 118.0 118.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#1] audio-beat-detection Cycles:10 Average:125.10 Median:130.00 stddev:16.74 (12.9%) stddev-sans-first:16.60 19:54:15 INFO - PROCESS | 1672 | Values: 142.0 122.0 108.0 145.0 111.0 112.0 114.0 109.0 150.0 138.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#2] audio-dft Cycles:10 Average:227.80 Median:243.00 stddev:37.87 (15.6%) stddev-sans-first:38.45 19:54:15 INFO - PROCESS | 1672 | Values: 259.0 266.0 277.0 277.0 227.0 200.0 195.0 195.0 190.0 192.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#3] audio-fft Cycles:10 Average:77.10 Median:82.00 stddev:8.33 (10.2%) stddev-sans-first:8.65 19:54:15 INFO - PROCESS | 1672 | Values: 82.0 65.0 83.0 75.0 78.0 83.0 82.0 85.0 78.0 60.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#4] audio-oscillator Cycles:10 Average:79.90 Median:84.00 stddev:14.81 (17.6%) stddev-sans-first:15.67 19:54:15 INFO - PROCESS | 1672 | Values: 83.0 71.0 87.0 58.0 85.0 103.0 72.0 93.0 58.0 89.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#5] imaging-gaussian-blur Cycles:10 Average:89.40 Median:90.00 stddev:1.96 (2.2%) stddev-sans-first:2.06 19:54:15 INFO - PROCESS | 1672 | Values: 90.0 88.0 91.0 89.0 91.0 87.0 93.0 87.0 90.0 88.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#6] imaging-darkroom Cycles:10 Average:110.90 Median:103.50 stddev:22.66 (21.9%) stddev-sans-first:23.75 19:54:15 INFO - PROCESS | 1672 | Values: 101.0 101.0 104.0 100.0 103.0 101.0 101.0 109.0 174.0 115.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:91.00 stddev:13.87 (15.2%) stddev-sans-first:14.61 19:54:15 INFO - PROCESS | 1672 | Values: 91.0 93.0 135.0 89.0 94.0 91.0 91.0 91.0 91.0 91.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#8] json-parse-financial Cycles:10 Average:65.40 Median:64.00 stddev:13.95 (21.8%) stddev-sans-first:13.64 19:54:15 INFO - PROCESS | 1672 | Values: 50.0 64.0 74.0 52.0 58.0 64.0 52.0 64.0 88.0 88.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#9] json-stringify-tinderbox Cycles:10 Average:82.70 Median:68.50 stddev:17.67 (25.8%) stddev-sans-first:17.92 19:54:15 INFO - PROCESS | 1672 | Values: 68.0 77.0 83.0 109.0 69.0 67.0 86.0 103.0 105.0 60.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#10] stanford-crypto-aes Cycles:10 Average:96.50 Median:86.00 stddev:13.10 (15.2%) stddev-sans-first:10.79 19:54:15 INFO - PROCESS | 1672 | Values: 120.0 76.0 99.0 105.0 106.0 106.0 88.0 93.0 88.0 84.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#11] stanford-crypto-ccm Cycles:10 Average:149.30 Median:144.00 stddev:23.42 (16.3%) stddev-sans-first:24.69 19:54:15 INFO - PROCESS | 1672 | Values: 142.0 145.0 137.0 138.0 136.0 121.0 161.0 164.0 206.0 143.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.70 Median:183.00 stddev:28.13 (15.4%) stddev-sans-first:29.18 19:54:15 INFO - PROCESS | 1672 | Values: 175.0 174.0 173.0 171.0 169.0 179.0 244.0 208.0 187.0 237.0 19:54:15 INFO - PROCESS | 1672 | 19:54:15 INFO - PROCESS | 1672 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.30 Median:72.00 stddev:9.99 (13.9%) stddev-sans-first:10.56 19:54:15 INFO - PROCESS | 1672 | Values: 73.0 91.0 92.0 85.0 69.0 67.0 68.0 71.0 69.0 68.0 19:54:15 INFO - PROCESS | 1672 | -------- Summary: end -------- 19:54:15 INFO - PROCESS | 1672 | 19:54:16 INFO - TEST-INFO | 1672: exit 0 19:54:16 INFO - TEST-OK | kraken | took 60923ms 19:54:16 INFO - SUITE-END | took 1174s 19:54:16 INFO - Completed test suite (00:19:34) 19:54:16 INFO - javascript benchmark 19:54:16 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7239.359131142382, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3402.8808991685282, 3438.535797667315, 3450.551428314322, 3456.9308613827716, 3464.722822174225, 5682.914756278659, 5749.3388260350075, 5782.103773915044, 5812.44781745734, 5887.175795388649, 5702.201691763146, 5706.7431965561545, 5722.7424765885535, 5788.594798364116, 5826.533877289818, 5170.793168273264, 5204.791808327663, 5286.3392075990505, 5322.308099947001, 5331.706756128411, 5271.578273738091, 5405.702686352249, 5421.593380496459, 5437.755301011444, 5482.150266708666, 5191.091558977201, 5224.656220019191, 5256.658317209407, 5283.524482796567, 5290.761915713802, 5232.3459567554055, 5259.342582177228, 5291.3121294231505, 5321.361436627591, 5364.758585863646, 5188.92216616751, 5203.375594928595, 5211.218317252405, 5266.052110620087, 5295.895855017751, 5628.915566266509, 5643.661380317153, 5663.688497132656, 5770.134479828017, 5784.82645520635, 6162.599431036968, 6177.042558403455, 6203.8138855834395, 6231.688415579203, 6294.559380843343, 6265.8120256392385, 6271.310155882849, 6277.8744425111245, 6283.3088360280335, 6298.622082675009, 5854.092615644582, 5916.26046744157, 5935.643861368331, 5936.139259807347, 5978.222831031938, 5277.472252774692, 5709.914351284734, 6139.416755408229, 6240.251169859555, 6240.344763799826, 4784.6889952153, 5197.7401129943355, 5617.494425501643, 5672.858178545487, 6184.721687524072, 6271.717772700239, 6304.464120549805, 6319.304876463585, 6328.240611126695, 6343.0, 5143.7685304161405, 5546.639468434539, 6101.176341193882, 6108.7861924833305, 6231.40801623845, 6051.304100028553, 6195.1636529068, 6256.311805701369, 6308.0, 6311.305756366796, 4230.5769423057445, 4994.775235114428, 5000.074986127578, 5004.699718016931, 5027.245913112989, 4399.49405818335, 4418.513963464016, 4429.712068715523, 4448.7330760153845, 4511.300748384006, 5205.063088644005, 5852.0, 5906.911396329058, 5910.940890591039, 5921.259842519685, 6185.567010309325, 6225.906611400833, 6257.74969001244, 6271.05934109878, 6278.183836101278, 5746.281714785652, 5814.3313518945015, 5831.504322132666, 5884.235049443545, 5939.0, 5421.024215641222, 5473.972630136824, 5494.692229263719, 5502.944970550323, 5523.226748255248, 6377.649229292433, 6405.839854003594, 6414.0, 6416.390442907917, 6431.774887878902, 6105.186826067751, 6175.4750846176785, 6250.593711408744, 6259.718312676032, 6268.498520118288, 6307.90538141919, 6310.968445157745, 6341.207349081365, 6416.871662566816, 6442.581232219891, 6241.188645476059, 6262.655553944577, 6263.65549894742, 6272.560920735505, 6277.748890044347, 6112.837854638657, 6262.93530099875, 6266.624002559861, 6268.905966410416, 6272.027835685476, 4776.21192503233, 5702.51528620072, 6101.9389806103145, 6115.816525504242, 6213.440790328892, 4966.875828104182, 5281.15501519755, 6048.334683184934, 6196.1015652731, 6216.595921265103, 5134.12719837621, 5399.487048730364, 5651.321841379061, 5914.645121292736, 6082.482988945989, 6052.273727152769, 6173.290071641639, 6188.195534580475, 6197.628142311295, 6214.5028397727165, 6041.2750469943885, 6053.213082299273, 6067.908981365372, 6071.5446341523675, 6082.78710245157], "value": 5683.3617902204505, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [519.5142541723487, 529.203548659268, 554.7586799742115, 558.9329280486343, 565.1353429253243, 37878.0530486738, 40771.59228407719, 40859.18281634366, 40985.79507102468, 41001.589984100116, 41622.375664365056, 41980.160396792126, 42055.57944420555, 42061.78969105158, 42120.15759684805, 9509.0, 9645.22838172948, 9677.129058384744, 9717.979612140729, 9725.12473877351, 9596.376235544667, 9703.22374210065, 9703.61185552577, 9719.222462203043, 9734.853977190347, 8396.874046889301, 8488.23605875471, 9555.0, 9683.564239609235, 9702.805943881118, 9617.23062155026, 9646.131848133666, 9691.12779849817, 9702.07830256121, 9716.562754676057, 8318.251357377805, 8812.1187881212, 9104.543273076342, 9577.712668619952, 9591.328606997513, 32976.010719678205, 35850.924472265615, 37014.44478332827, 39418.0145496362, 39586.60413395858, 3312.1057314525183, 3374.8481318340732, 3453.033150717803, 3456.2050728332374, 3559.48387483816, 24153.87923060391, 26091.869540652177, 29457.410851783057, 29827.850860745773, 30468.54297185544, 3147.35479226758, 3284.556584861037, 3379.8648054078053, 3384.2554637979606, 3384.509246159293, 492647.0, 497379.0, 499750.0, 500214.4989275067, 500806.0, 59649.0, 59972.200833975054, 63337.0, 65413.34586654072, 65447.0, 52414.737926310125, 63809.0, 67340.6632966832, 67399.66300168468, 68161.3183868155, 15913.443029493912, 16151.03093814375, 16376.344946202256, 16381.754273685905, 16392.26234819436, 12419.0, 12481.937590312173, 12621.116521843383, 12627.368631568384, 12635.115541911979, 19577.314793982427, 19630.41108766739, 19651.901740491205, 20019.29932452386, 20047.699284510745, 7914.366850651934, 8263.173682631808, 8265.148523759293, 8300.335973122135, 8313.916860831436, 806.6491076381793, 828.2173346187915, 947.2706016367496, 994.4828689081781, 1073.0771536478778, 29583.704162958526, 29921.0, 29954.850225749167, 30630.387392252476, 30927.381452370828, 1029.25893356783, 1063.8297872340543, 1104.9116070714324, 1149.2357582207737, 1174.2836869509592, 14881.776773348409, 15338.0, 15665.765013524806, 15824.76262856058, 15938.043717376995, 673307.6334618295, 674541.0, 679128.0, 679505.0, 681238.0, 40734.38898416526, 42102.15795684069, 42280.57719422828, 42421.78789106035, 42511.93720157021, 1125.3682160866776, 1344.3143996561823, 1392.1577445645441, 1396.6787638843123, 1398.5524632117829, 28852.0, 29762.404751905273, 29864.104076877727, 30420.695793042672, 30679.693203067683, 50451.243231352295, 50975.74512127416, 51427.0, 51498.74250628723, 51666.48333516767, 581083.0, 591435.0, 593572.0, 594369.0, 596989.0, 26878.731212687624, 27613.0, 27633.171004869888, 42465.0, 43063.56936430596, 39678.0, 46617.76691116523, 46977.2953405706, 47306.29040564463, 47901.28148077851, 38688.0, 39148.608513915635, 39299.0, 39790.403143953445, 40262.0, 33242.66757332492, 38745.806270969595, 39073.023174420865, 40042.59957400505, 40461.797691012536, 31183.688163118077, 37473.62526374702, 37846.0, 37894.24211515809, 38344.0, 25634.487310254062, 32172.83913580417, 34241.82879085688, 34630.0, 34944.65055349414, 24199.51600968006, 25357.85889634934, 25424.491510170064, 25560.744392555836, 26092.0], "value": 19945.769590674026, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.8883573521497, 348.747158310225, 359.0700086775253, 370.2262271851829, 391.27613914258626, 18467.076646167705, 18641.347552835654, 18673.90663046683, 18896.0, 19404.805951940492, 19261.903690481562, 19906.90046549769, 20003.499912502193, 20481.487962800937, 20594.588108237866, 5323.334583177103, 5407.945920540794, 5597.888042239173, 5611.186377975191, 5624.746886390102, 5438.91841622376, 5496.258005169291, 5509.311336082989, 5518.586106042043, 5534.252875861747, 5276.182191760283, 5413.377461591928, 5490.149026900816, 5491.972540137293, 5494.340679118512, 5388.488093631099, 5518.668879867221, 5523.7238138093335, 5528.834134975958, 5540.833774986737, 5097.388313402378, 5295.285136506561, 5296.814611488618, 5326.493983071602, 5356.54469370104, 13115.803262951064, 13267.402966866513, 13271.270080145589, 13272.800907986388, 16758.832411675972, 1929.5947850951318, 2142.828573714099, 2297.689811875392, 2331.6269396896414, 2709.24141240453, 7295.0, 8797.868031979526, 8970.923489181274, 9034.45793252407, 10326.896731032668, 1839.0988415676222, 1865.6921607934673, 1892.1579896945864, 1912.368918256972, 1931.2082046360924, 123115.0, 125850.0, 128652.0, 129115.0, 131026.68973310335, 9257.166854983085, 9920.40477571348, 9958.30291879576, 9984.60061597528, 11284.943575282236, 10375.16998640107, 10748.838767418494, 11810.232334898204, 12420.01919539304, 12448.937755311166, 7983.72056978003, 8077.798055048671, 8099.230573095547, 8112.107668156498, 8184.386171037196, 8418.368622353348, 8424.073351931283, 8510.914890851136, 8604.526751028628, 8658.826823463622, 3835.4055121456217, 3839.9424008639894, 3888.2612303662213, 3903.0, 3925.3130702127014, 1683.8989660620402, 1929.0933261367136, 1971.2312198242696, 1974.1215159254282, 1977.2600111901722, 868.522312727997, 869.5043825019804, 879.8284334554701, 961.2166084641135, 998.8401855703053, 15209.771853422208, 19862.0, 20678.379648610266, 20720.689189662167, 20866.165353385695, 920.7283851263893, 942.2650332740474, 969.5103972493846, 984.9064338887795, 995.1590905684685, 10115.797684046278, 10472.162227021972, 11251.268667536584, 11262.774744505228, 11266.380349080715, 556270.0, 575464.0, 577955.0, 586199.0, 587935.0, 125767.0, 126979.0, 136184.31907840597, 141541.0, 141756.0, 928.2342067794096, 971.5385192033868, 972.2659392158874, 978.0, 994.7115336552318, 18291.26834926641, 19465.4160375189, 19510.31713890007, 19563.60872782565, 19714.60570788605, 53150.20274695805, 54728.45271547233, 54969.0, 55331.893362133334, 55903.0, 85585.57207213924, 85608.0, 85948.57025714831, 86505.56747216223, 86981.0, 7422.7381345172535, 7982.760517184493, 8003.799905002422, 8181.754547363588, 8298.958505207434, 10856.891431085589, 12729.236245825281, 12805.039622028571, 12998.155119917175, 13066.281354525212, 9114.544272786201, 11111.499982500645, 11258.549658013915, 11322.490487927898, 11540.711482213012, 10138.594456221668, 10449.425281609585, 10626.256162068581, 10971.286866353661, 11392.316461012366, 8332.458390204618, 10050.743657042869, 11423.030012196723, 11440.82838757436, 11537.538498460304, 7251.311125443074, 8008.879866802119, 8140.959295203486, 8183.3862460316905, 8210.63052162642, 10870.565177392815, 11234.213605047567, 11283.435828208721, 11297.43512824372, 11323.547058117912], "value": 9123.893080213835, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1525.4432132271718, 1569.270289315468, 1805.295934585512, 1850.5096149520389, 1921.5868588253531, 7906.2489063539015, 8089.433739638213, 8539.188777066185, 9117.452952822827, 9123.361364704473, 7220.422366210703, 7882.369410447151, 8839.248663863564, 8945.418547794388, 8951.149640784131, 2354.3643216331625, 2401.387646150238, 2401.543706695731, 2402.411409204747, 2402.6396040593963, 2358.84667496613, 2377.3462297868136, 2394.4851856850773, 2400.099962514057, 2402.6876506054277, 2113.8479528656917, 2335.4044718596733, 2380.8928598213033, 2403.903843846244, 2404.6393041043807, 1962.3524237001827, 2321.303608917318, 2334.6964894563685, 2340.110757911991, 2377.976220237793, 1998.5003749062735, 2000.7499062617173, 2361.327021798796, 2363.6218205087102, 2370.431096536836, 7273.599952002638, 8762.561871906442, 8910.50992195429, 8922.776930576723, 8993.865092023625, 4634.0, 4736.9526304736855, 5363.704996225206, 5437.293151890269, 5438.048341540215, 3081.7534597232393, 3520.1375662962737, 3646.08847788053, 3672.2839046385966, 3703.666669999713, 1048.8187273611945, 1066.7653116314398, 1081.9513121909533, 1088.4775307852196, 1090.1278976818592, 11694.883051169465, 11798.292102473879, 11859.644210673694, 11866.644000679908, 11878.168528202943, 4550.795214215368, 4651.86044418668, 4773.785179666923, 4801.783919723621, 4815.398075240595, 8566.14338566151, 10003.749906252402, 10057.899421005697, 10092.949535252423, 10122.645707400354, 5350.38470575881, 5382.811601594004, 5390.784368625289, 5395.433479484597, 5396.622236443412, 4400.317950717644, 4563.748993805306, 4746.43042834862, 4884.511548845087, 4928.211486162197, 3264.281857991238, 3896.922061558753, 3974.900627484278, 3975.860844870416, 4006.9198616027516, 1287.750881644803, 1297.4939773488347, 1297.8247936528637, 1305.8824705776526, 1309.9476020959055, 773.7756050745334, 852.855014647499, 908.7092130518171, 910.988802429303, 923.5659240157115, 4767.736549814302, 5343.465653434625, 5354.571634269249, 5795.681237531976, 5922.733476993546, 820.6471217376585, 908.6728777640044, 931.9953400232955, 933.7198840347869, 970.45169479244, 3935.5080614923136, 3977.721559490808, 4026.1479719329436, 4075.775832329191, 4087.6525495332635, 10226.948865255627, 10531.473426328654, 10911.399873007062, 10975.670729878117, 11001.559937602406, 10089.899101009043, 10644.285707144783, 10660.680179594625, 10830.78338433227, 11101.722456938642, 7345.853082938418, 8096.716614918568, 8298.174401631752, 8304.377171712025, 8312.501249925024, 5548.611597188158, 5878.265216847894, 5950.910736338872, 6057.757689692539, 6202.224721909762, 8557.272631826116, 10134.79730405373, 10210.132138768287, 10230.181585472996, 10231.7442063949, 10100.797984040131, 10185.0, 10219.1824654026, 10226.948865255627, 10268.229882758673, 6531.216254049545, 6570.868582628416, 7248.710051598088, 7444.255574442729, 7623.390128789794, 8237.341012719087, 8479.109693482096, 8488.660453581788, 8492.065872754116, 8560.400771946153, 7436.888446673413, 7546.509476883985, 7630.0, 7633.580153091633, 7706.730264440942, 7321.341079302888, 7336.339729424378, 7349.8162545936775, 7490.962545187239, 7518.962405187939, 6820.35206655367, 6973.12835895513, 6983.930160698328, 6988.685509151998, 7001.054857594363, 4754.928676069931, 4933.432655244693, 5077.162268225849, 5104.3619547556555, 5123.923141152811, 4698.7415692137265, 4885.95114048855, 4988.825391111293, 5010.749462526932, 5107.642465027412], "value": 4559.924350121118, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [277.38282321833907, 287.57008272632413, 307.60780005493, 313.0483330674749, 314.50622522639435, 5343.625946183769, 5488.423715509874, 5545.3363990802745, 5619.634723742959, 5646.20953066571, 5328.520433161015, 5393.973030134844, 5504.284443022401, 5521.475459831309, 5540.556755459564, 4215.704900656955, 4215.810288537008, 4218.261804184271, 4255.382969469425, 4258.44640196774, 3911.6334617556395, 4101.405296232045, 4239.788010599473, 4242.9151416971645, 4271.78641067947, 4107.671386289105, 4166.145940082276, 4176.770277634729, 4204.369344598319, 4211.831526738927, 3526.894193174209, 4022.3966405039187, 4194.2869712149, 4222.936655950163, 4229.196452673982, 3688.2254726507467, 3694.612065733086, 3698.2418604186078, 3699.278640665071, 3702.2595480903656, 4625.560571745679, 4654.837080702159, 5000.624953128494, 5041.294218809369, 5298.178782288713, 4066.9186616267507, 4801.375821143264, 4803.0874133914795, 4826.589739872115, 4831.541003604652, 5028.949710502921, 5523.8066667666435, 5535.916961245584, 5768.826935191951, 5790.826275211751, 3443.500692399588, 3762.3792074307707, 3970.9602903971168, 4003.83984640614, 4034.334334834749, 5074.640292264115, 6031.4270144336215, 6122.081687746829, 6125.5712100153005, 6171.809007802255, 3432.4851272308856, 3892.8832135035996, 4075.2053349596545, 4079.3065178919655, 4116.341385378325, 2877.4101309231555, 3965.4448377227213, 3989.8005099744896, 4014.959850401517, 4213.705040647125, 4690.742009189459, 4694.600958918462, 4713.387259656222, 4721.551452611996, 4725.598324142417, 4858.562729354305, 5695.117256825199, 5817.418258174233, 5825.38833422493, 5848.590598658053, 1173.7652469506133, 1209.38925842449, 1293.489071816627, 1350.8041334006514, 1363.1548439967085, 736.3887972982412, 809.2393150438569, 836.7071524966335, 842.8145807922248, 847.5719761520515, 3839.404892241651, 4120.958790412057, 4452.4211852458975, 4485.820567177277, 4568.657350698711, 4585.977070114628, 4638.97680511602, 5038.961038961039, 5070.6957582545165, 5099.719515426613, 4179.077624292799, 4581.5647513486165, 4589.3478347795435, 4620.435658209726, 4630.421197350332, 2391.09138527362, 2400.9867616141632, 2423.551642946061, 2480.5860659424316, 2651.7613414792763, 4273.465816772903, 5122.9743851281255, 5173.63784535079, 5197.532222100029, 5199.350081239845, 4671.318325402853, 4893.266010098514, 4922.778474968635, 4943.728094954812, 5123.38519377677, 4100.671946244293, 4217.768022758716, 4850.805967761321, 5156.2523434102595, 5163.251328557413, 4181.519125300508, 4722.952770472251, 4731.361266229015, 4843.878903027453, 5014.648974571745, 4450.488193857748, 4731.6751309633355, 4821.421429428491, 4915.8033678652455, 4956.950430495649, 4816.975915120402, 4994.900101997867, 5042.697438153723, 5184.0, 5187.170052791535, 2981.850907454575, 3075.0467355119986, 3152.5271209318785, 3225.8387080646344, 3319.9708090492027, 4870.805167793249, 5000.67495612784, 5025.422076461254, 5055.823046193365, 5068.72122033277, 4107.753534787922, 5591.245181900392, 5731.251968149754, 5777.566682498914, 5828.735907891594, 5151.768170432265, 5580.972095139498, 5771.5671324649975, 5806.200077975841, 5835.328937172279, 5093.0, 5280.419153892992, 5361.329833770778, 5382.896506216138, 5631.1271752878365, 5048.798048078036, 5312.7061222042585, 5598.076317407745, 5722.768120432951, 5811.094452773613, 5001.674891132065, 5438.70087145195, 5693.4875861172695, 5706.828795136153, 5747.48272655463], "value": 3942.5448667962005, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [604.9485793707535, 681.9488538359618, 712.8146681862714, 738.8116030412244, 789.8973133492643, 14851.480198193065, 15223.086614803105, 15299.464518741846, 15301.617459563517, 15388.538343849688, 14606.269686515685, 14726.926365368185, 14738.55784326472, 14749.188794616291, 14760.33578488968, 664.9401553860151, 684.7226873116396, 811.7524155132683, 814.4136221920209, 837.3803385494751, 821.6754382019099, 829.8381815545971, 843.2537204573965, 843.7637461510786, 843.8734189871507, 890.7773056735816, 891.8528442806961, 893.8927328720563, 893.9821203575924, 894.2667013049302, 881.2113158722971, 881.894172699277, 887.5118684723441, 888.3825741109931, 890.0254221627308, 657.3557913245047, 657.730330564471, 658.6114192626326, 659.910912026875, 660.7324033766332, 15443.691126177413, 15528.223588820627, 15545.0, 15557.766633500507, 15578.29897654597, 10900.563977440894, 14488.637784055378, 14751.483698070517, 14904.850951490454, 15095.169765662884, 11994.520219191309, 12041.638750837488, 12043.578474753342, 12047.518099276018, 12126.818097728541, 3315.9171020724434, 3852.210296889131, 3859.7298189126773, 3994.960050399488, 4015.7791321477307, 107500.31249218754, 121095.0, 123968.0, 124572.37713811464, 125590.0, 9486.335956483117, 10907.805091836259, 11416.37209953444, 11502.194846360675, 11899.0, 23137.421564460685, 23229.41926451852, 23496.647550286758, 23515.294541163792, 23671.763282366956, 8177.1414001530175, 8192.221738934792, 8240.21717936795, 8302.792430189294, 8345.123762005023, 4537.954620453819, 4603.0, 4648.279516674921, 4702.6473014524045, 4719.2685133803625, 3543.94684079739, 3642.21692336149, 3645.8906232813056, 3646.927061458809, 3655.049687081378, 635.7361694896566, 647.5564238496551, 655.7639249870043, 656.5601047298226, 661.2362721057186, 614.4163045107166, 651.2673242602073, 652.6997581112646, 655.6951017776756, 664.6809531424847, 8793.912060879436, 9029.898107569203, 9099.636014559475, 9492.236426171396, 9854.605815767432, 639.785671799943, 641.2913730328014, 642.1651852591613, 683.1597135523334, 686.7150132694976, 3930.8820735377985, 3984.521857377133, 4061.147159096623, 4498.640108791289, 4502.514170323785, 119516.0, 123909.0, 126032.0, 131458.0, 150272.2486387561, 101176.0, 129268.70731292755, 131520.0, 132541.67458325485, 133133.66866331213, 56859.43140568624, 72845.27154728491, 111003.0, 111347.44326278317, 112472.0, 9716.125548700367, 9784.630688883362, 10016.0, 10035.117959691248, 10092.697219083439, 61198.0, 62359.6882015587, 64478.677606611665, 65662.67168664126, 66656.00031999047, 39876.80061599673, 68762.0, 78772.60613696894, 81953.18046819694, 93580.0, 13250.867491325349, 13254.271015094262, 13392.196468211938, 13440.92472602209, 13461.461541538229, 10621.0, 12092.334921579397, 14749.778753318924, 15280.223404941244, 15688.529344119695, 14100.435982560582, 14144.292785360485, 14185.716285674434, 14240.715185696436, 14330.283485825876, 13729.725405491634, 13813.654658633615, 13966.4413423466, 14113.29433528299, 14582.270886455422, 13573.1856088635, 13770.934359846104, 13788.793168102688, 13943.233122178377, 14056.929715351358, 9632.95183524078, 10935.781284374109, 11315.037874084192, 11436.885631143583, 11442.885571144516, 9359.438433694, 9586.712398628053, 10355.223358248311, 10866.402347870942, 11495.885041149482], "value": 7740.746166035133, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1588.6, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 159.0, 119.0, 117.0, 118.0, 119.0, 117.0, 118.0, 118.0, 118.0], "value": 121.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 122.0, 108.0, 145.0, 111.0, 112.0, 114.0, 109.0, 150.0, 138.0], "value": 125.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [259.0, 266.0, 277.0, 277.0, 227.0, 200.0, 195.0, 195.0, 190.0, 192.0], "value": 227.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 65.0, 83.0, 75.0, 78.0, 83.0, 82.0, 85.0, 78.0, 60.0], "value": 77.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 71.0, 87.0, 58.0, 85.0, 103.0, 72.0, 93.0, 58.0, 89.0], "value": 79.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 91.0, 89.0, 91.0, 87.0, 93.0, 87.0, 90.0, 88.0], "value": 89.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 101.0, 104.0, 100.0, 103.0, 101.0, 101.0, 109.0, 174.0, 115.0], "value": 110.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 93.0, 135.0, 89.0, 94.0, 91.0, 91.0, 91.0, 91.0, 91.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 64.0, 74.0, 52.0, 58.0, 64.0, 52.0, 64.0, 88.0, 88.0], "value": 65.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 77.0, 83.0, 109.0, 69.0, 67.0, 86.0, 103.0, 105.0, 60.0], "value": 82.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 76.0, 99.0, 105.0, 106.0, 106.0, 88.0, 93.0, 88.0, 84.0], "value": 96.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 145.0, 137.0, 138.0, 136.0, 121.0, 161.0, 164.0, 206.0, 143.0], "value": 149.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 174.0, 173.0, 171.0, 169.0, 179.0, 244.0, 208.0, 187.0, 237.0], "value": 191.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 91.0, 92.0, 85.0, 69.0, 67.0, 68.0, 71.0, 69.0, 68.0], "value": 75.3, "unit": "score"}]}]} 19:54:16 INFO - Return code: 0 19:54:16 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:54:16 ERROR - # TBPL SUCCESS # 19:54:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:54:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:54:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:54:16 INFO - 2016-09-28 19:54:16.849 screenresolution[1677:11144] starting screenresolution argv=screenresolution get 19:54:16 INFO - 2016-09-28 19:54:16.852 screenresolution[1677:11144] Display 0: 1600x1200x32@60 19:54:16 INFO - 2016-09-28 19:54:16.858 screenresolution[1678:11147] starting screenresolution argv=screenresolution list 19:54:16 INFO - Available Modes on Display 0 19:54:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:54:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:54:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:54:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:54:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:54:17 INFO - Intel Iris: 19:54:17 INFO - Chipset Model: Intel Iris 19:54:17 INFO - Type: GPU 19:54:17 INFO - Bus: Built-In 19:54:17 INFO - VRAM (Dynamic, Max): 1536 MB 19:54:17 INFO - Vendor: Intel (0x8086) 19:54:17 INFO - Device ID: 0x0a2e 19:54:17 INFO - Revision ID: 0x0009 19:54:17 INFO - Displays: 19:54:17 INFO - Display: 19:54:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:54:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:54:17 INFO - Display Serial Number: 1600x1200 60 19:54:17 INFO - Main Display: Yes 19:54:17 INFO - Mirror: Off 19:54:17 INFO - Online: Yes 19:54:17 INFO - Rotation: Supported 19:54:17 INFO - Return code: 0 19:54:17 INFO - Running post-action listener: _resource_record_post_action 19:54:17 INFO - [mozharness: 2016-09-29 02:54:17.095295Z] Finished run-tests step (success) 19:54:17 INFO - Running post-run listener: _resource_record_post_run 19:54:17 INFO - Total resource usage - Wall time: 1211s; CPU: 55.0%; Read bytes: 32641536; Write bytes: 711183360; Read time: 196; Write time: 2029 19:54:17 INFO - TinderboxPrint: CPU usage
54.6% 19:54:17 INFO - TinderboxPrint: I/O read bytes / time
32,641,536 / 196 19:54:17 INFO - TinderboxPrint: I/O write bytes / time
711,183,360 / 2,029 19:54:17 INFO - TinderboxPrint: CPU idle
2,197.0 (45.4%) 19:54:17 INFO - TinderboxPrint: CPU system
979.1 (20.2%) 19:54:17 INFO - TinderboxPrint: CPU user
1,665.7 (34.4%) 19:54:17 INFO - TinderboxPrint: Swap in / out
436,326,400 / 0 19:54:17 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 387267072; Write bytes: 381421056; Read time: 29102; Write time: 783 19:54:17 INFO - run-tests - Wall time: 1176s; CPU: 55.0%; Read bytes: 31715840; Write bytes: 301311488; Read time: 185; Write time: 1181 19:54:17 INFO - Running post-run listener: _upload_blobber_files 19:54:17 INFO - Blob upload gear active. 19:54:17 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:54:17 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 19:54:17 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'] 19:54:17 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 19:54:17 INFO - (blobuploader) - INFO - Open directory for files ... 19:54:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:54:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:54:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:54:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:54:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:54:19 INFO - (blobuploader) - INFO - Done attempting. 19:54:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:54:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:54:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:54:19 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:54:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:54:19 INFO - (blobuploader) - INFO - Done attempting. 19:54:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:54:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:54:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:54:22 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:54:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:54:22 INFO - (blobuploader) - INFO - Done attempting. 19:54:22 INFO - (blobuploader) - INFO - Iteration through files over. 19:54:22 INFO - Return code: 0 19:54:22 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:54:22 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:54:22 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/503e7f8a421742f1c1ac73c2c873562b2f72bb6ebbd46e039ce0d9ec388cde1f48b7d830356539cb2480859153570a4b8736bbeab1f107716b277d4da3e9183d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10dee0538b099f73f9f79a82c9f605ed91021814f7601f0602f6931e9e659739162d06d2875600ca226478ad5c5930074c2607de8beea87c7d7069eeb7752501", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2ac094c770f031655d162cada7c72252affbcd018f509c08f03cbfc82ccc594a2ce1ad16af51ec43b22a0a809881cafae0b5aa6e8a4e69adde85d771ddc0465c"} 19:54:22 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:54:22 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:54:22 INFO - Contents: 19:54:22 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/503e7f8a421742f1c1ac73c2c873562b2f72bb6ebbd46e039ce0d9ec388cde1f48b7d830356539cb2480859153570a4b8736bbeab1f107716b277d4da3e9183d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10dee0538b099f73f9f79a82c9f605ed91021814f7601f0602f6931e9e659739162d06d2875600ca226478ad5c5930074c2607de8beea87c7d7069eeb7752501", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2ac094c770f031655d162cada7c72252affbcd018f509c08f03cbfc82ccc594a2ce1ad16af51ec43b22a0a809881cafae0b5aa6e8a4e69adde85d771ddc0465c"} 19:54:22 INFO - Running post-run listener: copy_logs_to_upload_dir 19:54:22 INFO - Copying logs to upload dir... 19:54:22 INFO - mkdir: /builds/slave/test/build/upload/logs 19:54:22 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.597242 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-28 19:54:22.505078) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 19:54:22.510482) ========= 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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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/503e7f8a421742f1c1ac73c2c873562b2f72bb6ebbd46e039ce0d9ec388cde1f48b7d830356539cb2480859153570a4b8736bbeab1f107716b277d4da3e9183d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10dee0538b099f73f9f79a82c9f605ed91021814f7601f0602f6931e9e659739162d06d2875600ca226478ad5c5930074c2607de8beea87c7d7069eeb7752501", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2ac094c770f031655d162cada7c72252affbcd018f509c08f03cbfc82ccc594a2ce1ad16af51ec43b22a0a809881cafae0b5aa6e8a4e69adde85d771ddc0465c"} build_url:https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011008 build_url: 'https://queue.taskcluster.net/v1/task/cgMqIUGSTIK4iir2f3h7rg/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/503e7f8a421742f1c1ac73c2c873562b2f72bb6ebbd46e039ce0d9ec388cde1f48b7d830356539cb2480859153570a4b8736bbeab1f107716b277d4da3e9183d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/10dee0538b099f73f9f79a82c9f605ed91021814f7601f0602f6931e9e659739162d06d2875600ca226478ad5c5930074c2607de8beea87c7d7069eeb7752501", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/2ac094c770f031655d162cada7c72252affbcd018f509c08f03cbfc82ccc594a2ce1ad16af51ec43b22a0a809881cafae0b5aa6e8a4e69adde85d771ddc0465c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 19:54:22.542926) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:54:22.543274) ========= 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.iZdnQXnwyk/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.hAOmQPISJ3/Listeners TMPDIR=/var/folders/2r/mjh615_91fs88xk2w8x_w5l800000w/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.005107 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 19:54:22.592680) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 19:54:22.593009) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 19:54:22.593330) ========= ========= Total master_lag: 0.17 =========