builder: graphics_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0370 starttime: 1480307897.65 results: success (0) buildid: 20161127172258 builduid: b2f4ffc267e648e09d6c39face58fbeb revision: d263165be1c99d4181bdc3275e697d4bb85bafcc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:17.652776) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:17.653296) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:17.653820) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:17.962451) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:17.962758) ========= 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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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-11-27 20:38:17-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.3M=0s 2016-11-27 20:38:18 (46.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.127380 ========= master_lag: 0.16 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:18.246582) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:18.246916) ========= 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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.025541 ========= master_lag: 0.07 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:18.345404) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-27 20:38:18.345845) ========= bash -c 'python archiver_client.py mozharness --repo projects/graphics --rev d263165be1c99d4181bdc3275e697d4bb85bafcc --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/graphics --rev d263165be1c99d4181bdc3275e697d4bb85bafcc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bMbHkRiRbD/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.3ORGtiNDPS/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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-11-27 20:38:18,519 truncating revision to first 12 chars 2016-11-27 20:38:18,519 Setting DEBUG logging. 2016-11-27 20:38:18,519 attempt 1/10 2016-11-27 20:38:18,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/graphics/d263165be1c9?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-27 20:38:20,086 unpacking tar archive at: graphics-d263165be1c9/testing/mozharness/ program finished with exit code 0 elapsedTime=1.822785 ========= master_lag: 0.09 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-27 20:38:20.254885) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:20.255198) ========= script_repo_revision: d263165be1c99d4181bdc3275e697d4bb85bafcc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:20.255572) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:20.255847) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-27 20:38:20.278963) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-27 20:38:20.279315) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Graphics', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Graphics'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.bMbHkRiRbD/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.3ORGtiNDPS/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:38:20 INFO - MultiFileLogger online at 20161127 20:38:20 in /builds/slave/test 20:38:20 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Graphics --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Graphics 20:38:20 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:38:20 INFO - {'append_to_log': False, 20:38:20 INFO - 'base_work_dir': '/builds/slave/test', 20:38:20 INFO - 'blob_upload_branch': 'Graphics', 20:38:20 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:38:20 INFO - 'branch': 'Graphics', 20:38:20 INFO - 'buildbot_json_path': 'buildprops.json', 20:38:20 INFO - 'config_files': ('talos/mac_config.py',), 20:38:20 INFO - 'default_actions': ('clobber', 20:38:20 INFO - 'read-buildbot-config', 20:38:20 INFO - 'download-and-extract', 20:38:20 INFO - 'populate-webroot', 20:38:20 INFO - 'create-virtualenv', 20:38:20 INFO - 'install', 20:38:20 INFO - 'run-tests'), 20:38:20 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:38:20 INFO - 'download_minidump_stackwalk': True, 20:38:20 INFO - 'download_symbols': 'ondemand', 20:38:20 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:38:20 INFO - 'tooltool.py': '/tools/tooltool.py', 20:38:20 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:38:20 INFO - '/tools/misc-python/virtualenv.py')}, 20:38:20 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:38:20 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:38:20 INFO - 'installer_path': 'installer.exe', 20:38:20 INFO - 'log_level': 'info', 20:38:20 INFO - 'log_name': 'talos', 20:38:20 INFO - 'log_to_console': True, 20:38:20 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:38:20 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:38:20 INFO - 'opt_config_files': (), 20:38:20 INFO - 'pip_index': False, 20:38:20 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:38:20 INFO - 'cmd': ('bash', 20:38:20 INFO - '-c', 20:38:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:38:20 INFO - 'enabled': True, 20:38:20 INFO - 'halt_on_failure': False, 20:38:20 INFO - 'name': 'check_screen_resolution'},), 20:38:20 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:38:20 INFO - 'cmd': ('bash', 20:38:20 INFO - '-c', 20:38:20 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:38:20 INFO - 'enabled': True, 20:38:20 INFO - 'halt_on_failure': False, 20:38:20 INFO - 'name': 'check_screen_resolution'},), 20:38:20 INFO - 'run_cmd_checks_enabled': True, 20:38:20 INFO - 'sps_profile': False, 20:38:20 INFO - 'sps_profile_interval': 0, 20:38:20 INFO - 'suite': 'dromaeojs-e10s', 20:38:20 INFO - 'system_bits': '32', 20:38:20 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:38:20 INFO - 'title': 't-yosemite-r7-0370', 20:38:20 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:38:20 INFO - 'use_talos_json': True, 20:38:20 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:38:20 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:38:20 INFO - 'work_dir': 'build'} 20:38:20 INFO - [mozharness: 2016-11-28 04:38:20.501848Z] Running clobber step. 20:38:20 INFO - Running pre-action listener: _resource_record_pre_action 20:38:20 INFO - Running main action method: clobber 20:38:20 INFO - rmtree: /builds/slave/test/build 20:38:20 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:38:23 INFO - Running post-action listener: _resource_record_post_action 20:38:23 INFO - [mozharness: 2016-11-28 04:38:23.347111Z] Finished clobber step (success) 20:38:23 INFO - [mozharness: 2016-11-28 04:38:23.347261Z] Running read-buildbot-config step. 20:38:23 INFO - Running pre-action listener: _resource_record_pre_action 20:38:23 INFO - Running main action method: read_buildbot_config 20:38:23 INFO - Using buildbot properties: 20:38:23 INFO - { 20:38:23 INFO - "project": "", 20:38:23 INFO - "product": "firefox", 20:38:23 INFO - "script_repo_revision": "default", 20:38:23 INFO - "builddir": "graphics_yosemite_r7_test-dromaeojs-e10s", 20:38:23 INFO - "repository": "", 20:38:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 graphics talos dromaeojs-e10s", 20:38:23 INFO - "buildid": "20161127172258", 20:38:23 INFO - "pgo_build": "False", 20:38:23 INFO - "basedir": "/builds/slave/test", 20:38:23 INFO - "buildnumber": 5, 20:38:23 INFO - "slavename": "t-yosemite-r7-0370", 20:38:23 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 20:38:23 INFO - "platform": "yosemite_r7", 20:38:23 INFO - "scheduler": "tests-graphics-macosx64-talos-8-3600", 20:38:23 INFO - "branch": "graphics", 20:38:23 INFO - "repo_path": "projects/graphics", 20:38:23 INFO - "revision": "d263165be1c99d4181bdc3275e697d4bb85bafcc", 20:38:23 INFO - "stage_platform": "macosx64", 20:38:23 INFO - "builduid": "b2f4ffc267e648e09d6c39face58fbeb", 20:38:23 INFO - "slavebuilddir": "test" 20:38:23 INFO - } 20:38:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 20:38:23 INFO - Running post-action listener: _resource_record_post_action 20:38:23 INFO - [mozharness: 2016-11-28 04:38:23.350534Z] Finished read-buildbot-config step (success) 20:38:23 INFO - [mozharness: 2016-11-28 04:38:23.350675Z] Running download-and-extract step. 20:38:23 INFO - Running pre-action listener: _resource_record_pre_action 20:38:23 INFO - Running main action method: download_and_extract 20:38:23 INFO - mkdir: /builds/slave/test/build/tests 20:38:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:38:23 INFO - trying https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 20:38:23 INFO - Downloading https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:38:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 20:38:24 INFO - Downloaded 1199 bytes. 20:38:24 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 20:38:24 INFO - Using the following test package requirements: 20:38:24 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:38:24 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 20:38:24 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 20:38:24 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'jsshell-mac.zip'], 20:38:24 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 20:38:24 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 20:38:24 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 20:38:24 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 20:38:24 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 20:38:24 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 20:38:24 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 20:38:24 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:38:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:38:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:38:24 INFO - Fetch https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:38:25 INFO - Http code: 200 20:38:25 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 20:38:25 INFO - x-amz-cf-id: FMwUuKqnWLV3725WN5zWPQvZ11BHdqHG_WMNMm3Z_PK2iCQDDlAWHA== 20:38:25 INFO - x-amz-version-id: RuJCNiGGL0lLTEAgfjH63mgCNutNtKJg 20:38:25 INFO - Expected file size: 16267207 20:38:25 INFO - Obtained file size: 16267207 20:38:32 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:38:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 20:38:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:38:32 INFO - Fetch https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 20:38:32 INFO - Http code: 200 20:38:32 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 20:38:32 INFO - x-amz-cf-id: snePNIk9oILo02X1d0P0Qtntz8eddhUTacOyO7MTub0r2j5ZWKuzYg== 20:38:32 INFO - x-amz-version-id: RuJCNiGGL0lLTEAgfjH63mgCNutNtKJg 20:38:32 INFO - Expected file size: 16267207 20:38:32 INFO - Obtained file size: 16267207 20:38:39 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 20:38:39 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:38:39 INFO - Fetch https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 20:38:39 INFO - Http code: 200 20:38:39 INFO - via: 1.1 ff555a0cc6346f82fe74596f8ed6cc33.cloudfront.net (CloudFront) 20:38:39 INFO - x-amz-cf-id: cTqybhJsaQdTPXxPvXrlE7zmOYzBJDjIhT2kkYWs7OXhFMITrWVghA== 20:38:39 INFO - x-amz-version-id: vrQ2grBqL.j5rGcvxnRimv.E0fATjMpL 20:38:39 INFO - Expected file size: 14409506 20:38:39 INFO - Obtained file size: 14409506 20:38:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:38:40 INFO - trying https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:38:40 INFO - Downloading https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 20:38:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 20:38:43 INFO - Downloaded 107549216 bytes. 20:38:43 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:38:43 INFO - mkdir: /builds/slave/test/properties 20:38:43 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:38:43 INFO - Writing to file /builds/slave/test/properties/build_url 20:38:43 INFO - Contents: 20:38:43 INFO - build_url:https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 20:38:43 INFO - Running post-action listener: _resource_record_post_action 20:38:43 INFO - Running post-action listener: set_extra_try_arguments 20:38:43 INFO - [mozharness: 2016-11-28 04:38:43.931886Z] Finished download-and-extract step (success) 20:38:43 INFO - [mozharness: 2016-11-28 04:38:43.932025Z] Running populate-webroot step. 20:38:43 INFO - Running pre-action listener: _resource_record_pre_action 20:38:43 INFO - Running main action method: populate_webroot 20:38:43 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:38:43 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:38:43 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:38:43 INFO - '--tppagecycles', 20:38:43 INFO - '7'], 20:38:43 INFO - 'tests': ['tsvgm']}}, 20:38:43 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:38:43 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:38:43 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:38:43 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:38:43 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:38:43 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:38:43 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['damp', 'tps']}, 20:38:43 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'tests': ['damp', 'tps']}, 20:38:43 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['dromaeo_dom']}, 20:38:43 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:38:43 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['basic_compositor_video']}, 20:38:43 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:38:43 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['a11yr', 20:38:43 INFO - 'ts_paint', 20:38:43 INFO - 'tpaint', 20:38:43 INFO - 'sessionrestore', 20:38:43 INFO - 'sessionrestore_no_auto_restore', 20:38:43 INFO - 'tabpaint']}, 20:38:43 INFO - 'other-e10s': {'tests': ['a11yr', 20:38:43 INFO - 'ts_paint', 20:38:43 INFO - 'tpaint', 20:38:43 INFO - 'sessionrestore', 20:38:43 INFO - 'sessionrestore_no_auto_restore', 20:38:43 INFO - 'tabpaint']}, 20:38:43 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:38:43 INFO - 'ts_paint', 20:38:43 INFO - 'tpaint', 20:38:43 INFO - 'sessionrestore', 20:38:43 INFO - 'sessionrestore_no_auto_restore', 20:38:43 INFO - 'tabpaint']}, 20:38:43 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:38:43 INFO - 'ts_paint', 20:38:43 INFO - 'tpaint', 20:38:43 INFO - 'sessionrestore', 20:38:43 INFO - 'sessionrestore_no_auto_restore', 20:38:43 INFO - 'tabpaint']}, 20:38:43 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['a11yr', 20:38:43 INFO - 'ts_paint', 20:38:43 INFO - 'tpaint', 20:38:43 INFO - 'sessionrestore', 20:38:43 INFO - 'sessionrestore_no_auto_restore', 20:38:43 INFO - 'tabpaint']}, 20:38:43 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['a11yr', 20:38:43 INFO - 'ts_paint', 20:38:43 INFO - 'tpaint', 20:38:43 INFO - 'sessionrestore', 20:38:43 INFO - 'sessionrestore_no_auto_restore', 20:38:43 INFO - 'tabpaint']}, 20:38:43 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['tsvgx', 20:38:43 INFO - 'tsvgr_opacity', 20:38:43 INFO - 'tart', 20:38:43 INFO - 'tscrollx', 20:38:43 INFO - 'cart']}, 20:38:43 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:38:43 INFO - 'tsvgr_opacity', 20:38:43 INFO - 'tart', 20:38:43 INFO - 'tscrollx', 20:38:43 INFO - 'cart']}, 20:38:43 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'talos_options': ['--disable-e10s'], 20:38:43 INFO - 'tests': ['tp5o']}, 20:38:43 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:38:43 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'talos_options': ['--disable-e10s', 20:38:43 INFO - '--xperf_path', 20:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:38:43 INFO - 'tests': ['tp5n']}, 20:38:43 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:38:43 INFO - 'talos_options': ['--xperf_path', 20:38:43 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:38:43 INFO - 'tests': ['tp5n']}}, 20:38:43 INFO - 'talos.zip': {'path': '', 20:38:43 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:38:43 INFO - Running post-action listener: _resource_record_post_action 20:38:43 INFO - [mozharness: 2016-11-28 04:38:43.950010Z] Finished populate-webroot step (success) 20:38:43 INFO - [mozharness: 2016-11-28 04:38:43.950117Z] Running create-virtualenv step. 20:38:43 INFO - Running pre-action listener: _resource_record_pre_action 20:38:43 INFO - Running main action method: create_virtualenv 20:38:43 INFO - Creating virtualenv /builds/slave/test/build/venv 20:38:43 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 20:38:43 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:38:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:38:44 INFO - Using real prefix '/tools/python27' 20:38:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:38:44 INFO - Installing distribute.............................................................................................................................................................................................done. 20:38:47 INFO - Installing pip.................done. 20:38:47 INFO - Return code: 0 20:38:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:38:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:38:47 INFO - Reading from file tmpfile_stdout 20:38:47 INFO - Output received: 20:38:47 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) 20:38:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:38: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')]} 20:38:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:38:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38: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')]} 20:38:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:38:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:38: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', 'pip>=1.5'] in /builds/slave/test/build 20:38: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 pip>=1.5 20:38:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:38:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:38:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:38:47 INFO - 'HOME': '/Users/cltbld', 20:38:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:38:47 INFO - 'LOGNAME': 'cltbld', 20:38:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:38:47 INFO - 'MOZ_NO_REMOTE': '1', 20:38:47 INFO - 'NO_EM_RESTART': '1', 20:38:47 INFO - 'PAGER': '/bin/cat', 20:38:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:38:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:38:47 INFO - 'PWD': '/builds/slave/test', 20:38:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:38:47 INFO - 'SHELL': '/bin/bash', 20:38:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:38:47 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:38:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:38:47 INFO - 'USER': 'cltbld', 20:38:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:38:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:38:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:38:47 INFO - 'XPC_FLAGS': '0x0', 20:38:47 INFO - 'XPC_SERVICE_NAME': '0', 20:38:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:38:48 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 20:38:48 INFO - Cleaning up... 20:38:48 INFO - Return code: 0 20:38:48 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:38:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:38:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:38:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:38:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 20:38:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 20:38:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:38:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:38:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:38:48 INFO - 'HOME': '/Users/cltbld', 20:38:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:38:48 INFO - 'LOGNAME': 'cltbld', 20:38:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:38:48 INFO - 'MOZ_NO_REMOTE': '1', 20:38:48 INFO - 'NO_EM_RESTART': '1', 20:38:48 INFO - 'PAGER': '/bin/cat', 20:38:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:38:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:38:48 INFO - 'PWD': '/builds/slave/test', 20:38:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:38:48 INFO - 'SHELL': '/bin/bash', 20:38:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:38:48 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:38:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:38:48 INFO - 'USER': 'cltbld', 20:38:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:38:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:38:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:38:48 INFO - 'XPC_FLAGS': '0x0', 20:38:48 INFO - 'XPC_SERVICE_NAME': '0', 20:38:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:38:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:38:48 INFO - Downloading/unpacking psutil>=3.1.1 20:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:38:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:38:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:38:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:38:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:38:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:38:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:38:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:38:52 INFO - Installing collected packages: psutil 20:38:52 INFO - Running setup.py install for psutil 20:38:52 INFO - building 'psutil._psutil_osx' extension 20:38:52 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 20:38:53 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 20:38:53 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 20:38:53 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 20:38:53 INFO - building 'psutil._psutil_posix' extension 20:38:53 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 20:38:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:38:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:38:53 INFO - ^ 20:38:53 INFO - 1 warning generated. 20:38:53 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 20:38:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:38:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:38:53 INFO - Successfully installed psutil 20:38:53 INFO - Cleaning up... 20:38:53 INFO - Return code: 0 20:38:53 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:38:53 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')]} 20:38:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:38:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38:53 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')]} 20:38:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:38:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38:53 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:38:53 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 20:38:53 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 20:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:38:53 INFO - 'HOME': '/Users/cltbld', 20:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:38:53 INFO - 'LOGNAME': 'cltbld', 20:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:38:53 INFO - 'MOZ_NO_REMOTE': '1', 20:38:53 INFO - 'NO_EM_RESTART': '1', 20:38:53 INFO - 'PAGER': '/bin/cat', 20:38:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:38:53 INFO - 'PWD': '/builds/slave/test', 20:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:38:53 INFO - 'SHELL': '/bin/bash', 20:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:38:53 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:38:53 INFO - 'USER': 'cltbld', 20:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:38:53 INFO - 'XPC_FLAGS': '0x0', 20:38:53 INFO - 'XPC_SERVICE_NAME': '0', 20:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:38:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:38:53 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:38: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 20:38: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 20:38: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 20:38: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 20:38: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 20:38: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 20:38:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:38:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:38:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:38:56 INFO - Installing collected packages: mozsystemmonitor 20:38:56 INFO - Running setup.py install for mozsystemmonitor 20:38:56 INFO - Successfully installed mozsystemmonitor 20:38:56 INFO - Cleaning up... 20:38:56 INFO - Return code: 0 20:38:56 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:38:56 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')]} 20:38:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:38:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38:56 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')]} 20:38:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:38:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:38:56 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:38:56 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 20:38:56 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 20:38:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:38:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:38:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:38:56 INFO - 'HOME': '/Users/cltbld', 20:38:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:38:56 INFO - 'LOGNAME': 'cltbld', 20:38:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:38:56 INFO - 'MOZ_NO_REMOTE': '1', 20:38:56 INFO - 'NO_EM_RESTART': '1', 20:38:56 INFO - 'PAGER': '/bin/cat', 20:38:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:38:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:38:56 INFO - 'PWD': '/builds/slave/test', 20:38:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:38:56 INFO - 'SHELL': '/bin/bash', 20:38:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:38:56 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:38:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:38:56 INFO - 'USER': 'cltbld', 20:38:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:38:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:38:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:38:56 INFO - 'XPC_FLAGS': '0x0', 20:38:56 INFO - 'XPC_SERVICE_NAME': '0', 20:38:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:38:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:38:57 INFO - Downloading/unpacking jsonschema==2.5.1 20:38:57 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 20:38:57 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 20:38:57 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 20:38:57 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 20:38:57 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 20:38:57 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 20:38:59 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:38:59 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:38:59 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 20:38:59 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 20:38:59 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 20:38:59 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 20:38:59 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 20:38:59 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 20:39:00 INFO - Downloading functools32-3.2.3-2.tar.gz 20:39:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:39:00 INFO - warning: no files found matching '*.txt' 20:39:00 INFO - no previously-included directories found matching 'build' 20:39:00 INFO - no previously-included directories found matching 'dist' 20:39:00 INFO - no previously-included directories found matching '.git*' 20:39:00 INFO - Installing collected packages: jsonschema, functools32 20:39:00 INFO - Running setup.py install for functools32 20:39:00 INFO - warning: no files found matching '*.txt' 20:39:00 INFO - no previously-included directories found matching 'build' 20:39:00 INFO - no previously-included directories found matching 'dist' 20:39:00 INFO - no previously-included directories found matching '.git*' 20:39:00 INFO - Successfully installed jsonschema functools32 20:39:00 INFO - Cleaning up... 20:39:00 INFO - Return code: 0 20:39:00 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:00 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:39:00 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 20:39:00 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 20:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:00 INFO - 'HOME': '/Users/cltbld', 20:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:00 INFO - 'LOGNAME': 'cltbld', 20:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:00 INFO - 'MOZ_NO_REMOTE': '1', 20:39:00 INFO - 'NO_EM_RESTART': '1', 20:39:00 INFO - 'PAGER': '/bin/cat', 20:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:00 INFO - 'PWD': '/builds/slave/test', 20:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:00 INFO - 'SHELL': '/bin/bash', 20:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:00 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:00 INFO - 'USER': 'cltbld', 20:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:00 INFO - 'XPC_FLAGS': '0x0', 20:39:00 INFO - 'XPC_SERVICE_NAME': '0', 20:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:00 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:39:00 INFO - Cleaning up... 20:39:00 INFO - Return code: 0 20:39:00 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:00 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:39:00 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 20:39:00 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 20:39:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:00 INFO - 'HOME': '/Users/cltbld', 20:39:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:00 INFO - 'LOGNAME': 'cltbld', 20:39:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:00 INFO - 'MOZ_NO_REMOTE': '1', 20:39:00 INFO - 'NO_EM_RESTART': '1', 20:39:00 INFO - 'PAGER': '/bin/cat', 20:39:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:00 INFO - 'PWD': '/builds/slave/test', 20:39:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:00 INFO - 'SHELL': '/bin/bash', 20:39:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:00 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:00 INFO - 'USER': 'cltbld', 20:39:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:00 INFO - 'XPC_FLAGS': '0x0', 20:39:00 INFO - 'XPC_SERVICE_NAME': '0', 20:39:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:01 INFO - Downloading/unpacking blobuploader==1.2.4 20:39:01 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 20:39:01 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 20:39:01 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 20:39:01 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 20:39:01 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 20:39:01 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 20:39:03 INFO - Downloading blobuploader-1.2.4.tar.gz 20:39:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:39:03 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:39:04 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:39:04 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 20:39:04 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 20:39:04 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 20:39:04 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 20:39:04 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 20:39:04 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 20:39:04 INFO - Downloading docopt-0.6.1.tar.gz 20:39:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:39:05 INFO - Installing collected packages: blobuploader, requests, docopt 20:39:05 INFO - Running setup.py install for blobuploader 20:39:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:39:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:39:05 INFO - Running setup.py install for requests 20:39:05 INFO - Running setup.py install for docopt 20:39:05 INFO - Successfully installed blobuploader requests docopt 20:39:05 INFO - Cleaning up... 20:39:05 INFO - Return code: 0 20:39:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:06 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:39:06 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 20:39:06 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 20:39:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:06 INFO - 'HOME': '/Users/cltbld', 20:39:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:06 INFO - 'LOGNAME': 'cltbld', 20:39:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:06 INFO - 'MOZ_NO_REMOTE': '1', 20:39:06 INFO - 'NO_EM_RESTART': '1', 20:39:06 INFO - 'PAGER': '/bin/cat', 20:39:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:06 INFO - 'PWD': '/builds/slave/test', 20:39:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:06 INFO - 'SHELL': '/bin/bash', 20:39:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:06 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:06 INFO - 'USER': 'cltbld', 20:39:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:06 INFO - 'XPC_FLAGS': '0x0', 20:39:06 INFO - 'XPC_SERVICE_NAME': '0', 20:39:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-MeqL4I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-iMnFnu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-3ehA4R-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-CNQ0lY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-yv18Ss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-ntThcp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Le4Zqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:39:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:39:06 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-MRoOEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-lOOxlV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-4qGhz1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-UqoKHt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-IA2uxL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-bSCQ97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-aRCCoa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-xDzJXi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-mLPX9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:39:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:39:07 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-gX9HE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:39:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:39:08 INFO - Running setup.py install for manifestparser 20:39:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:39:08 INFO - Running setup.py install for mozcrash 20:39:08 INFO - Running setup.py install for mozdebug 20:39:08 INFO - Running setup.py install for mozdevice 20:39:08 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:39:08 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:39:08 INFO - Running setup.py install for mozfile 20:39:08 INFO - Running setup.py install for mozhttpd 20:39:08 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:39:08 INFO - Running setup.py install for mozinfo 20:39:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Running setup.py install for mozInstall 20:39:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Running setup.py install for mozleak 20:39:09 INFO - Running setup.py install for mozlog 20:39:09 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Running setup.py install for moznetwork 20:39:09 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Running setup.py install for mozprocess 20:39:09 INFO - Running setup.py install for mozprofile 20:39:09 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:39:09 INFO - Running setup.py install for mozrunner 20:39:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:39:10 INFO - Running setup.py install for mozscreenshot 20:39:10 INFO - Running setup.py install for moztest 20:39:10 INFO - Running setup.py install for mozversion 20:39:10 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:39:10 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:39:10 INFO - Cleaning up... 20:39:10 INFO - Return code: 0 20:39:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39:10 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')]} 20:39:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:10 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')]} 20:39:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:10 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:39:10 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 20:39:10 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 20:39:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:10 INFO - 'HOME': '/Users/cltbld', 20:39:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:10 INFO - 'LOGNAME': 'cltbld', 20:39:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:10 INFO - 'MOZ_NO_REMOTE': '1', 20:39:10 INFO - 'NO_EM_RESTART': '1', 20:39:10 INFO - 'PAGER': '/bin/cat', 20:39:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:10 INFO - 'PWD': '/builds/slave/test', 20:39:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:10 INFO - 'SHELL': '/bin/bash', 20:39:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:10 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:10 INFO - 'USER': 'cltbld', 20:39:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:10 INFO - 'XPC_FLAGS': '0x0', 20:39:10 INFO - 'XPC_SERVICE_NAME': '0', 20:39:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:39:10 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-zRYG72-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-4sdCjS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-WNgkFR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-VcSUAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:39:11 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-mQXhzJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-JiEDQY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-DJZnl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-EkylKC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-b51YTB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-JoacHv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:39:11 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)) 20:39:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:39:11 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-7v16EB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:39:12 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)) 20:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:39:12 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-RWkl5y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:39:12 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)) 20:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:39:12 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-nTY80O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:39:12 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)) 20:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:39:12 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-RNEtji-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 20:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:12 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Lg_MbN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:12 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)) 20:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:39:12 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-QWTL5O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:39:12 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)) 20:39:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:39:12 INFO - Running setup.py (path:/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/pip-Y26wbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:39:12 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)) 20:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:39:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:39:12 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)) 20:39:12 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:39:12 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.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:39:12 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:39:12 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 20:39:12 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 20:39:12 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 20:39:12 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 20:39:12 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 20:39:12 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 20:39:15 INFO - Downloading blessings-1.6.tar.gz 20:39:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:39:15 INFO - Installing collected packages: blessings 20:39:15 INFO - Running setup.py install for blessings 20:39:15 INFO - Successfully installed blessings 20:39:15 INFO - Cleaning up... 20:39:15 INFO - Return code: 0 20:39:15 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:39:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:39:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:39:15 INFO - Reading from file tmpfile_stdout 20:39:15 INFO - Current package versions: 20:39:15 INFO - blessings == 1.6 20:39:15 INFO - blobuploader == 1.2.4 20:39:15 INFO - docopt == 0.6.1 20:39:15 INFO - functools32 == 3.2.3-2 20:39:15 INFO - jsonschema == 2.5.1 20:39:15 INFO - manifestparser == 1.1 20:39:15 INFO - mozInstall == 1.12 20:39:15 INFO - mozcrash == 1.0 20:39:15 INFO - mozdebug == 0.1 20:39:15 INFO - mozdevice == 0.49 20:39:15 INFO - mozfile == 1.2 20:39:15 INFO - mozhttpd == 0.7 20:39:15 INFO - mozinfo == 0.9 20:39:15 INFO - mozleak == 0.1 20:39:15 INFO - mozlog == 3.4 20:39:15 INFO - moznetwork == 0.27 20:39:15 INFO - mozprocess == 0.23 20:39:15 INFO - mozprofile == 0.28 20:39:15 INFO - mozrunner == 6.13 20:39:15 INFO - mozscreenshot == 0.1 20:39:15 INFO - mozsystemmonitor == 0.3 20:39:15 INFO - moztest == 0.7 20:39:15 INFO - mozversion == 1.4 20:39:15 INFO - psutil == 3.1.1 20:39:15 INFO - requests == 1.2.3 20:39:15 INFO - wsgiref == 0.1.2 20:39:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:15 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:39:15 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 20:39:15 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 20:39:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:15 INFO - 'HOME': '/Users/cltbld', 20:39:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:15 INFO - 'LOGNAME': 'cltbld', 20:39:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:15 INFO - 'MOZ_NO_REMOTE': '1', 20:39:15 INFO - 'NO_EM_RESTART': '1', 20:39:15 INFO - 'PAGER': '/bin/cat', 20:39:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:15 INFO - 'PWD': '/builds/slave/test', 20:39:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:15 INFO - 'SHELL': '/bin/bash', 20:39:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:15 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:15 INFO - 'USER': 'cltbld', 20:39:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:15 INFO - 'XPC_FLAGS': '0x0', 20:39:15 INFO - 'XPC_SERVICE_NAME': '0', 20:39:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 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)) 20:39:16 INFO - Cleaning up... 20:39:16 INFO - Return code: 0 20:39:16 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:16 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 0x10701e3f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106f94570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7d355f800>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, '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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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 20:39:16 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 20:39:16 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 20:39:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:16 INFO - 'HOME': '/Users/cltbld', 20:39:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:16 INFO - 'LOGNAME': 'cltbld', 20:39:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:16 INFO - 'MOZ_NO_REMOTE': '1', 20:39:16 INFO - 'NO_EM_RESTART': '1', 20:39:16 INFO - 'PAGER': '/bin/cat', 20:39:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:16 INFO - 'PWD': '/builds/slave/test', 20:39:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:16 INFO - 'SHELL': '/bin/bash', 20:39:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:16 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:16 INFO - 'USER': 'cltbld', 20:39:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:16 INFO - 'XPC_FLAGS': '0x0', 20:39:16 INFO - 'XPC_SERVICE_NAME': '0', 20:39:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:16 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:39:16 INFO - Cleaning up... 20:39:16 INFO - Return code: 0 20:39:16 INFO - Running post-action listener: _resource_record_post_action 20:39:16 INFO - Running post-action listener: _start_resource_monitoring 20:39:16 INFO - Starting resource monitoring. 20:39:16 INFO - [mozharness: 2016-11-28 04:39:16.473583Z] Finished create-virtualenv step (success) 20:39:16 INFO - [mozharness: 2016-11-28 04:39:16.474586Z] Running install step. 20:39:16 INFO - Running pre-action listener: _resource_record_pre_action 20:39:16 INFO - Running main action method: install 20:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:39:16 INFO - Reading from file tmpfile_stdout 20:39:16 INFO - Detecting whether we're running mozinstall >=1.0... 20:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:39:16 INFO - Reading from file tmpfile_stdout 20:39:16 INFO - Output received: 20:39:16 INFO - Usage: mozinstall [options] installer 20:39:16 INFO - Options: 20:39:16 INFO - -h, --help show this help message and exit 20:39:16 INFO - -d DEST, --destination=DEST 20:39:16 INFO - Directory to install application into. [default: 20:39:16 INFO - "/builds/slave/test"] 20:39:16 INFO - --app=APP Application being installed. [default: firefox] 20:39:16 INFO - mkdir: /builds/slave/test/build/application 20:39:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:39:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:39:49 INFO - Reading from file tmpfile_stdout 20:39:49 INFO - Output received: 20:39:49 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:39:49 INFO - Running post-action listener: _resource_record_post_action 20:39:49 INFO - [mozharness: 2016-11-28 04:39:49.943669Z] Finished install step (success) 20:39:49 INFO - [mozharness: 2016-11-28 04:39:49.943810Z] Running run-tests step. 20:39:49 INFO - Running pre-action listener: _resource_record_pre_action 20:39:49 INFO - Running main action method: run_tests 20:39:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:39:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:39:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:39:49 INFO - 2016-11-27 20:39:49.958 screenresolution[1654:10633] starting screenresolution argv=screenresolution get 20:39:49 INFO - 2016-11-27 20:39:49.961 screenresolution[1654:10633] Display 0: 1600x1200x32@60 20:39:49 INFO - 2016-11-27 20:39:49.967 screenresolution[1655:10636] starting screenresolution argv=screenresolution list 20:39:49 INFO - Available Modes on Display 0 20:39:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:39:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:39:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:39:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:39:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:39:50 INFO - Intel Iris: 20:39:50 INFO - Chipset Model: Intel Iris 20:39:50 INFO - Type: GPU 20:39:50 INFO - Bus: Built-In 20:39:50 INFO - VRAM (Dynamic, Max): 1536 MB 20:39:50 INFO - Vendor: Intel (0x8086) 20:39:50 INFO - Device ID: 0x0a2e 20:39:50 INFO - Revision ID: 0x0009 20:39:50 INFO - Displays: 20:39:50 INFO - Display: 20:39:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:39:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:39:50 INFO - Display Serial Number: 1600x1200 60 20:39:50 INFO - Main Display: Yes 20:39:50 INFO - Mirror: Off 20:39:50 INFO - Online: Yes 20:39:50 INFO - Rotation: Supported 20:39:50 INFO - Return code: 0 20:39:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:39:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:39:50 INFO - Python 2.7.3 20:39:50 INFO - Return code: 0 20:39:50 INFO - grabbing minidump binary from tooltool 20:39: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')]} 20:39:50 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 0x106e34d30>, '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 0x106c9bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x106e2cb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:39:50 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 20:39:50 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 20:39:50 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 20:39:50 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:39:50 INFO - Return code: 0 20:39:50 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:39:50 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:39:50 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:39:50 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:39:50 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:39:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 20:39:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Graphics --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0370 --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 20:39:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.bMbHkRiRbD/Render', 20:39:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:50 INFO - 'HOME': '/Users/cltbld', 20:39:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:50 INFO - 'LOGNAME': 'cltbld', 20:39:50 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:39:50 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:39:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:50 INFO - 'MOZ_NO_REMOTE': '1', 20:39:50 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:39:50 INFO - 'NO_EM_RESTART': '1', 20:39:50 INFO - 'PAGER': '/bin/cat', 20:39:50 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:50 INFO - 'PWD': '/builds/slave/test', 20:39:50 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:39:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:50 INFO - 'SHELL': '/bin/bash', 20:39:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3ORGtiNDPS/Listeners', 20:39:50 INFO - 'TMPDIR': '/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/', 20:39:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:50 INFO - 'USER': 'cltbld', 20:39:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:50 INFO - 'XPC_FLAGS': '0x0', 20:39:50 INFO - 'XPC_SERVICE_NAME': '0', 20:39:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:50 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Graphics', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0370', '--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 20:39:50 INFO - mozversion application_buildid: 20161127172258 20:39:50 INFO - mozversion application_changeset: d263165be1c99d4181bdc3275e697d4bb85bafcc 20:39:50 INFO - mozversion application_display_name: Nightly 20:39:50 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:39:50 INFO - mozversion application_name: Firefox 20:39:50 INFO - mozversion application_remotingname: firefox 20:39:50 INFO - mozversion application_repository: https://hg.mozilla.org/projects/graphics 20:39:50 INFO - mozversion application_vendor: Mozilla 20:39:50 INFO - mozversion application_version: 53.0a1 20:39:50 INFO - mozversion platform_buildid: 20161127172258 20:39:50 INFO - mozversion platform_changeset: d263165be1c99d4181bdc3275e697d4bb85bafcc 20:39:50 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/graphics 20:39:50 INFO - mozversion platform_version: 53.0a1 20:39:50 INFO - using testdate: 1480307990 20:39:50 INFO - actual date: 1480307990 20:39:50 INFO - starting webserver on 'localhost:49246' 20:39:50 INFO - SUITE-START | Running 2 tests 20:39:50 INFO - TEST-START | dromaeo_css 20:39:50 INFO - operating with platform_type : mac_ 20:39:50 INFO - Initialising browser for dromaeo_css test... 20:39:50 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp0QVWN_/profile http://localhost:49246/getInfo.html) 20:39:53 INFO - PROCESS | 1665 | 2016-11-27 20:39:53.655 plugin-container[1667:10823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 20:39:53 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:39:53 INFO - PROCESS | 1665 | 2016-11-27 20:39:53.659 plugin-container[1667:10823] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 20:39:53 INFO - PROCESS | 1665 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:39:53 INFO - PROCESS | 1665 | 2016-11-27 20:39:53.659 plugin-container[1667:10823] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:39:53 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 20:39:53 INFO - PROCESS | 1665 | colorDepth:24 20:39:53 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 20:39:53 INFO - PROCESS | 1665 | __metrics 20:39:53 ERROR - PROCESS | 1665 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:39:54 INFO - TEST-INFO | 1665: exit 0 20:39:54 INFO - Browser initialized. 20:39:54 INFO - Running cycle 1/1 for dromaeo_css test... 20:39:54 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp0QVWN_/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:39:57 INFO - PROCESS | 1668 | 2016-11-27 20:39:57.299 plugin-container[1669:10945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 20:39:57 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:39:57 INFO - PROCESS | 1668 | 2016-11-27 20:39:57.300 plugin-container[1669:10945] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 20:39:57 INFO - PROCESS | 1668 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:39:57 INFO - PROCESS | 1668 | 2016-11-27 20:39:57.300 plugin-container[1669:10945] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:40:07 INFO - PROCESS | 1668 | RSS: Main: 242380800 20:40:07 INFO - PROCESS | 1668 | 20:40:56 INFO - PROCESS | 1668 | 1480308056632 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] 20:43:09 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49246/tests/dromaeo/cssquery-ext.html) 20:43:11 INFO - PROCESS | 1668 | RSS: Main: 250085376 20:43:11 INFO - PROCESS | 1668 | 20:46:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-ext.html (next: http://localhost:49246/tests/dromaeo/cssquery-jquery.html) 20:46:14 INFO - PROCESS | 1668 | RSS: Main: 260157440 20:46:14 INFO - PROCESS | 1668 | 20:49:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49246/tests/dromaeo/cssquery-mootools.html) 20:49:17 INFO - PROCESS | 1668 | RSS: Main: 266350592 20:49:17 INFO - PROCESS | 1668 | 20:52:19 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49246/tests/dromaeo/cssquery-prototype.html) 20:52:19 INFO - PROCESS | 1668 | RSS: Main: 265093120 20:52:19 INFO - PROCESS | 1668 | 20:55:22 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49246/tests/dromaeo/cssquery-yui.html) 20:55:23 INFO - PROCESS | 1668 | RSS: Main: 270098432 20:55:23 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49246/tests/dromaeo/cssquery-yui.html (next: http://localhost:49246/tests/dromaeo/cssquery-dojo.html) 20:58:25 INFO - PROCESS | 1668 | RSS: Main: 272969728 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | __start_tp_report 20:58:25 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 20:58:25 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 20:58:25 INFO - PROCESS | 1668 | |i|pagename|runs| 20:58:25 INFO - PROCESS | 1668 | |0;dojo.html;3296.571445712057;3321.518379834924;3359.5128706337573;3361.2437201629637;3379.6789305016046;4503.256962601167;5077.41609714882;5143.765496280894;5576.498115169634;5633.267675202218;5570.080936645459;5615.691136987464;5646.096624540074;5653.1520271959125;5689.089745640698;5174.611904107188;5196.272521846926;5200.50595193457;5235.476452354772;5242.973785131088;5131.666441681279;5180.715060671661;5203.427622961472;5248.947510524884;5262.789488420478;5238.319018527605;5261.053010458116;5272.787258930448;5284.392294886099;5285.365756109272;5118.769655365518;5145.228215767665;5157.226416037587;5199.1161502544655;5203.089459344599;4608.631309495232;5034.496550344973;5142.919986802776;5171.095058364771;5217.139172036609;5059.83623766532;5352.625316227865;5615.438456154393;5680.6591604503865;5691.971540142315;6168.938310616882;6181.320054794014;6183.288921773964;6194.163787888622;6201.503879689614;5997.130416089686;6095.847603809941;6112.724927378279;6113.572049956505;6116.908246376308;5721.885562288814;5785.334686511063;5796.246487956539;5797.88404231913;5820.097884827858;6113.263474096151;6134.681512609229;6140.600860944024;6148.569600127948;6169.475594574422;5942.3166335871065;6015.30823955242;6058.606190597597;6081.543884208702;6125.111858780452;6157.291911430153;6158.076288556752;6158.476529494954;6159.29168145669;6168.907466388007;5758.62568933018;5936.7328470218945;6094.603850749687;6097.603655762361;6139.84650383744;6101.786437474667;6124.258880972529;6131.325554189125;6161.353057928853;6188.381161883775;4899.167141585939;4928.088303663833;4949.480304567968;4949.7030178189425;4950.2822090796635;4441.244988351988;4448.755318457451;4451.154280686659;4451.577100175478;4477.932831007537;4483.910321793611;4686.859394218179;5485.369182543979;5620.494155526022;5682.374938756816;5463.289772329651;5769.134629805562;6207.317195108534;6215.471684906744;6226.25284965798;5691.77232910679;5723.942760572341;5736.598438109292;5736.942630573724;5771.6537007778825;5267.236250743621;5303.867403314871;5315.362156541162;5361.383440904268;5380.946190538123;6136.4578081083455;6159.907601385983;6182.567220294716;6188.288346840171;6236.407541283571;6105.572609917263;6175.84560385994;6196.442320191204;6196.659183744938;6225.128482012612;6125.356837532173;6243.750249989949;6257.812265632038;6259.906101408574;6260.092286618506;5077.517635824591;6057.757689692539;6122.602030867979;6131.172291740558;6144.723487443165;6132.540059495466;6138.357744607992;6139.308154112039;6178.289496707936;6196.411340922605;5566.415526369673;6050.515958723379;6052;6122.7244773984385;6125.173101631401;6114.38856114385;6115.449609535163;6117.265928088658;6123.418275263843;6130.325664177026;5376.838694839161;5717.514011309086;5829.766809327749;5941.287045554561;5964.493018093337;5990.4908082813445;5992.101184822312;6048.879022419439;6059.030814985059;6127.540434467343;5865.794697185578;5913.733881975236;5917.201177841109;5920;5960.850978725566 20:58:25 INFO - PROCESS | 1668 | |1;ext.html;441.6577152706654;473.8388947757763;513.7431284357821;519.9584033277338;552.3096129837705;26073.608895866553;31924.68075319246;38689.419658705105;39827.80086099565;39892;41149.58850411495;41713;41815.79092104543;41845.58154418455;41892.743217703515;9159.038300978385;9323.813523729521;9329.160375566198;9338.346315757866;9344.345895787297;8384.874226886572;8596.140385961417;9165.495897725623;9521.80956380872;10651.307665001788;11176.385298808562;11179;11225.719357016058;11229.831552526719;11244.775104497905;11173.7206569836;11197.888021119767;11218;11275.43622818864;11288.717782055432;11005.889941100566;11105.666829995112;11199.160062995226;11201.27191732543;11218.607348742755;39022.414663780066;39348.016299592455;39360.409593856115;39420;39426.802865985774;2769.556870900646;2947.985260073686;3228.2252259457796;3556.768810027366;3709.5733990591157;26454.338641533923;27184.45631087387;30115.397692046256;30424.8478757607;30562.777488900418;3074.8923787667322;3115.875364985398;3142.874285028596;3251.2522119912314;3257.0717345556404;431932;441660;443568.385896844;444690;448942.75528622477;55583;56928.146077808866;61029;61412;61467.07799383013;53826.7308663462;61853;62012;62246.688766555875;62981;15587.688246235239;16061.518154455385;16081;16094.517164485083;16122.67754644924;10648.680539583826;12174.39128043612;12273.140880138482;12306.261624302391;12355.505779768708;15393.923030384776;15614.45349412765;16735.326467353167;17315.65368692619;17874.92750434978;6261.24865016201;6264.5301602380005;6272.843178920472;6337.556371054075;6344.651044192614;739;886.9755432475501;922.7231830450838;974.9853752193723;1094.655183617158;29246;29468.705312947026;29925.401491970475;30120;30344.696553034188;925.3013974449248;1033.7570670892335;1037.5175543372368;1046.0637729232294;1106.618216715232;15191.848081519043;15216.543503694907;15331.003484773453;15634.140122293384;15656.4520241791;185046;877457;882086;895662;897999;56405.717971410704;56974.71512642493;57026.714866426235;57065;57083.42916570864;1214.6720385495867;1306.2880730001982;1317.236003118189;1339.6449940765706;1352.5333759852836;30073.66647726668;30212;30391.392172156877;30690.539641905834;30845;49885.002299954525;49904.500954989984;50251.24623130729;50305.49694503008;50490;564871.8102551869;599226.0038699779;605958;608202;609675;22858.97134628979;25253.87373063123;25497.107601233867;25577.872110640074;25592.744072559035;32522.83738581292;38874.22251555009;45179.77410112928;45802.31296530488;47613;37300.81349593234;37430.62569374272;37851.81074094722;38914.6108538911;38962;37477.4378384319;37671.81164094162;38211.80894095512;38809.2238155241;38925.416118757676;36460.45309320415;36785.81607092055;37056.629433706396;37375.878723638336;37622.8118859404;32381.866634667673;33596.66403336033;34192.82903585466;34233.144171395914;34365;20413.38759837207;24797.752022479544;25074.74925250724;25094.87073081679;25269.87365063163 20:58:25 INFO - PROCESS | 1668 | |2;jquery.html;362.4726023635609;369.68022660398753;374.7657713928795;376.1630372421361;394.0090671960019;18627.627447451043;19010.42968710939;19394.612107757835;19608.901955490204;19838.908860012692;11323;11715.238509496898;11746.119041071912;11768.176227664046;11813.173077884552;5928.436798504146;6547.57440766351;6606.735730570771;6626.8674626507445;6720.428763555104;4484.887877803065;6540.411362977331;6590.901136482933;7255.528390654617;7647.655855486517;7344.11870575529;7720.884186737204;7734.071911370644;7737.148913619498;7745.65144568493;7409.88885166723;7544.660490277895;7546.54720716756;7554.546727196331;7569.810754731148;6664.766733164316;7194.64026798658;7372.631368431611;7384.409247260206;7393.149787774421;26797.866010669823;27238.182854514398;27546.5868011978;27608.861955690292;27764.861175694194;2163.7619861815188;2385.522895420923;2389.295157928398;2440.1947357372023;2509.4102885821817;13506.527271545547;15308.234588270654;16311.124220714531;16531.66936661272;16823.91588042064;1967.9212831486723;1979.910904009323;1983.4248068060244;2015.6371853066441;2018.8082132197417;133926;147699;151700;151856.48810800372;152055;9242.491662958602;9854.802903942025;9890.060444257784;9903.554340054714;21193.470163245733;12274.815877761841;12305.815412768816;12333.93833030847;12340.568080117153;12392.442340094718;7787.493812902143;7862.056553213651;7920.643571039317;7957.641906114239;8045.678172873019;8144.877826832602;8156.388270879707;8291.58542072894;8324.542150181676;8331.791705207297;3760.2479504099288;3852.691784657277;3865.594112618191;3867.477890484805;3925.1364699766004;1772.822717728217;1822.026362073399;1824.3979486769335;1894.5547796267865;1908.8186622270862;855.5209082913589;860.165639329849;913.0869130869131;917.2936838634348;938.7183844846519;18866.3396781112;19348.903255483634;20100.899495502428;20185.394438166877;20228.393148205578;839.5340585974842;864.831357885219;907.3195103672246;915.455304094063;944.3389627260945;10265.486725663686;10667.413292269;10675.519601617945;10695.411752353695;10712.839307410395;416959;449011.2648310278;604419;612268.9386553039;626143.3464163342;100371;132674.33662831623;135491;136446.31776841052;138757;906.1934877958622;919.5999740113073;930.283681565191;937.4609599480326;946.1248345280588;12871.935640321739;13880;17879.821201787814;17936.923784572446;18358.632827343645;36854.8157259212;48276.758616206695;54593.727031364586;55266.72366638141;56846.44191011332;72024.63987680028;73267.63366183135;73718.26281737328;74619;74656.62671686607;7590.430717696085;7828.491148075357;8016.599170041358;8087.191280871866;8206.794830129294;8961.059088795844;11546.59586914454;12180;12553.686157846127;12652.936735316265;10351.534180962026;10386.740331491774;10943.398113103856;10982.890171098186;11092.001719845253;10486.37081775096;10578.259521833397;10803.945980270048;10946.726331841768;10947.781044379228;10621;10691.465426728788;11092.279001864854;11093.44532773342;11180.720481988015;7788.403377307752;7922.326602238874;7982.401319901147;8020.117787043304;8025.8394832104195;8895.421797583136;9627.79652543432;10037.899621003697;10964.890351096386;10968 20:58:25 INFO - PROCESS | 1668 | |3;mootools.html;1451.1510766201777;1486.0563542150735;1504.0299007140395;1699.8215187405315;1706.2236682309551;7396.334329910307;7692.499987500809;7877.645505952231;8316.168383161688;8325.333973282139;7768.844623107534;8088.797780055502;8089;8232.753017409472;8292.336613070955;2294.288770481152;2326.581215381231;2327.94180145496;2336.2874323331375;2345.44881952741;2322.4890524084676;2341.8594884306913;2361.574916515035;2362.9054837806466;2366.230974933145;2300.4478925058033;2308.4575124845646;2324.2794733632627;2333.5216280662507;2336.1122773346187;1914.2343062774862;2157.4067131538955;2288.5880541502524;2307.7807608277185;2311.0178174276034;1716.9698181091371;1777.742227377036;1982.9107690153978;2064.3394113883564;2327.1622216002224;6600.702968366435;7691.153973062958;8168.959155204246;8309.875351869727;8317.792055198608;4972.701637901738;5132.230165475171;5139.665921715114;5142.3829140503;5143.3570803649545;2941.7646588272887;3154.936901261962;3209.0472174117144;3341.2816244507303;3400.4049291373913;904.5477261369315;922.2345453273766;1044.993612264454;1053.8155822731069;1064.9563427840683;10621.628243011535;10699.679009629646;10728.141748660088;10729.463526823705;10762.677119686421;4364.41080454141;4611.446626404853;4631.791569379386;4665.066986602693;4679.555442232982;7858.410619203582;7917.564533950688;9217.308701847254;9276.350655454053;9306.162445379949;5132.897342053138;5135.589152867761;5145.073886700355;5150.768215430315;5154.639175257771;4059.4316795648633;4625.496713568104;4709;4709.270063140219;4816.710997340171;3378.307446973414;3549.4498352755363;3692.2800053989213;3775.3581891078584;3843.1545060086737;1247.4074814463058;1258.8992880569533;1262.5959692898368;1262.8421447319085;1268.1440027981075;853.9786505337293;863.2058506174335;870.6647940542937;889.6174644902754;911.0479548871446;4713.670043097019;5607.551395888319;5676.829695109153;5680.261565996394;5935.228420305332;841.3521588377038;860.3547339495378;898.7528429681889;920.5857364186143;934.845750451168;3391.847366868497;3552.685506362662;3720.5535335759882;3739.700823934079;3816.160444702161;8889.333300002396;10084.193264538819;10100.74748131288;10106.343087699275;10169;9944;10057.597696092074;10058.748531286776;10098.747531311628;10150.796984060278;7383.9261607384315;7756.534607923542;7919.920800792033;7941.2058794121285;7941.483803552751;4769.451513075902;5074.80742025631;5337.466253374631;5520.696361700145;5554.111342185231;6781.491388146008;9050.732897394239;9097.681581144629;9098.090190980849;9129.497877616795;7591.3547348475995;8190.590470476572;9084.364094513321;9218.170364667212;9250.629974800933;6482.70827812759;6743.696533656095;6806.965965170142;7019.613921234381;7138.286171382821;7635.88546171819;7800.765977020698;7818.804529886798;7829.217078292125;7852.293293603603;6866.382025617719;7006.439484841098;7013.824654383681;7057.682404291922;7112.715491380287;6907;6927.549709268881;6957.443404527525;6968.581885086911;7022.613756243456;6368.681565921778;6551.737930482727;6622.437092846969;6670.232923213893;6672.299408562031;4729.456112546964;4787.042591481787;4801.95198048029;4842.443119041355;4888.095702295087;4649.256119020941;4685.953140468688;4715.669903106749;4727.267273572602;4890.07088653169 20:58:25 INFO - PROCESS | 1668 | |4;prototype.html;282.42808313165835;301.6500859003555;305.6683498404231;313.0514540940948;314.9889753858615;4588.426446694163;4632.305154226863;4686.55477729616;4763.523647635239;4836.46798852126;4675.88310292242;5187.377514698242;5220.164773636209;5220.321358223435;5244.763985620647;3517.7537572369943;4034.9798251008706;4038.1519880825063;4053.1083161704523;4054.6958978076614;3057.6330840299197;3889.6499315061637;4050.8784736457956;4058.8782336529953;4073.920411091048;3467.930641387183;3647.361711700455;3867.168558759866;3965.4448377227213;3988.521377434712;3161.762867784914;3961.2869683456966;3975.3241948868763;3998.400239964014;4027.154297597479;2829.9104844634758;2993.431248062883;3495.4057810172076;3521.506989021539;3523.7709548879243;4119.567445418218;4478.731276123411;4832.381152313979;5024.773885175176;5072.590519503244;4151.605597468236;4518.954810451919;4589.2198326284615;4601.792919318638;4634.513376095529;4233.264361611725;4619.72281663098;5557.6109672322955;5562.108882979806;5611.691356975405;3237.9028629140926;3256.902292931216;3655.5796083450477;3799.4490798834013;3884.76691398517;5720.971395143039;5788.739506722208;5798.971005144947;5820.1269809528485;5830.30036395631;3916.5104361954754;3930.941035884464;3935.3900145477496;3954.426608141803;3965.385365268388;3648.5770549485724;3849.4995650565247;3856.688725833174;3917.138229589486;4015.056461731491;4253.574642535722;4418.690691651618;4508.526604706459;4570.223062079454;4579.198640237945;5676.517496012802;5685.403032681592;5687.857803554862;5689.345725241567;5705.686187259741;1187.2876274235562;1213.5873802906876;1242.403646249787;1281.2127082719553;1287.5300515311844;741.6921977379435;776.7087342246657;862.7799911022774;863.7797361672733;875.444093000949;3871.903202419962;4086.8773936781945;4170.561156401037;4234.407182994383;4881.609471242292;5002.274670172877;5092.516210959953;5379.632961496929;5480.561555075585;5511.972440137773;4135.855245066408;4580.511333816523;4873.415190177201;4899.387576552931;4915.926261106087;2653.6550248467574;2833.546632538784;2857.8285302881895;2858.3140046388717;2872.5547540131315;5003.4746351633285;5580.2745643066955;5657.575681823797;5658.434156584309;5738.971305143447;4897.1674815281485;5883.823485295448;5964.582479226499;5999.730012149376;6011.248593925759;5023.974880125649;5313.707746073926;5955.255593050869;6058.303295121117;6101.694915254308;5064.721440320818;5420;5619.49424551776;5817.563682723728;5864.354920958776;5090.2364645303505;5462.098753705594;5746.885062298647;5759.884802304015;5777.2200752897825;5726.396608949501;6019.22432882292;6039.456448919618;6047.218279591437;6055.697215139314;3264.8041117533025;3400.8639654413546;3793.1086194744767;3870.0262865939135;3883.8834834954996;5230.320058392384;5311.946880531145;5842.795502157404;5862.325832529144;5865.680221950027;5276.736163191748;5642.971785141048;5651.4348565143155;5658.943410566007;5676.545876329801;4640.69835460694;4940.308356830118;5587.6088673794075;5597.188407680945;5644.887102258014;5053.646244762831;5284;5495.862603434946;5540.972295138498;5573.303337082864;4567.314902764612;4913.926291105565;5625.718714064362;5663.830085097454;5682.971585142048;4583.760021979589;5476.808311709071;5537.28015358017;5558.943800677858;5561.805336813192 20:58:25 INFO - PROCESS | 1668 | |5;yui.html;702.4029574861368;704.7462913351189;728.2389902551832;753.4273951796633;761.4859969520576;11424.828627570572;11753.177277590572;12464.314462704548;12649.430775615096;12666.17669851459;13062.30190075289;13197.868021319784;13198.142120762168;13204.801927971064;13310.93344533276;655.1221363373079;682.1200651159998;816.779469543223;826.0335407573124;843.5571325054334;852.3862818770475;852.4842470305468;854.2226573817827;855.3969451536652;858.4076986879064;893.2095095840195;893.7810236492036;896.3411892259185;897.8563429851225;900.5362238447204;881.8589025755848;893.2362829780574;893.5353616119646;893.8212357528488;896.085992287866;660.4386271669091;661.2263651527724;665.3180489997791;669.7053296549502;671.9899201511932;13374.598762037058;13507.86492135076;13533.052686311961;13576.59270221895;13964.93017534916;13479.595612131652;13554.525591604344;13567.59297221085;13592.38834252461;13841.30793460323;9143.359964802466;9302.534873256378;9358.485283309416;9439.480828554426;9656.951715241448;3257.9248847880144;3465.42820434628;3579.8210089495683;3590.3716849551483;3594.730395220343;89829;89868;90059.54970225172;90441.99999999935;93335.53332233295;10501.579936802595;10507.264491485676;10526.157907367546;10677.359358438363;10898.128149748001;18266.725999110025;20817.271395501386;20839.958002099833;20857.89571052135;20927.79072209289;7608.31525162738;7611.856181703021;7623.199564045695;7670.884936725954;7819.530828150329;4220.683448741357;4270.743755374688;4276.807543660543;4307.7415355078165;4323.308270676676;2965.8368789716774;3322.4850148227065;3416.7095796857047;3417.231122997355;3652.1782598915033;639.6961443314389;640.8914670733328;646.3536463536464;662.9767958121539;691.9861602768017;572.373251289837;614.7510258345377;633.499535367064;646.7833275852643;656.9441597464173;8741.229182517573;9023.774405639911;9030.683926062557;9035.819283614292;9104.408213466104;625.2809269340295;644.6228956060663;657.7500549791049;663.1909070933453;669.8158006548239;4213.346931225665;4289.292266775947;4429.579189976947;4479.731216127043;4502.561337287644;106023.4698826501;108825.45587272171;110956;112719.43640281746;112859.43570282261;65898.34101659017;92778.53610731903;99069.12101426769;100191.99616007639;101522;46525.534744652796;81880.59059704782;82771;85554;85977;9295.07049295065;9633.373830700983;9715.319927605;9730.90785481125;9754.492987649788;46287.768561156976;46662.76668616635;53010.939781203386;53158.73420632872;55618.72190639021;55447.44552554423;69073.65463172652;73124.63437682777;74000;77383.61308193424;11850.76298474043;12310.322932238812;12608.74782504363;12657.746845063231;12817.359132043173;10405.791884162425;12191.817122742988;12535.749285014066;12962.611121666365;13407.396667149807;10705.785884282426;11873.940630297138;13078.542251021168;13405.396757145756;13457.663558411117;12834.67913302175;13064.34678266102;13148.737025259632;13418.865811341762;13624.386902589606;12765.170263932814;12773.55292564793;12775.61673149807;12879.742405152032;12970.286634235208;10197.370062629261;10312.535935882754;10395.792084158425;10448.738781530523;10996.560137594726;9007.63969441215;10721.195910306602;10879.728006799893;10947.45262736844;10982.945085274523 20:58:25 INFO - PROCESS | 1668 | __end_tp_report 20:58:25 INFO - PROCESS | 1668 | __start_cc_report 20:58:25 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,369 20:58:25 INFO - PROCESS | 1668 | __end_cc_report 20:58:25 INFO - PROCESS | 1668 | __startTimestamp1480309105945__endTimestamp 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | ------- Summary: start ------- 20:58:25 INFO - PROCESS | 1668 | Number of tests: 6 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | [#0] dojo.html Cycles:165 Average:5641.74 Median:5829.77 stddev:637.47 (10.9%) stddev-sans-first:612.30 20:58:25 INFO - PROCESS | 1668 | Values: 3296.6 3321.5 3359.5 3361.2 3379.7 4503.3 5077.4 5143.8 5576.5 5633.3 5570.1 5615.7 5646.1 5653.2 5689.1 5174.6 5196.3 5200.5 5235.5 5243.0 5131.7 5180.7 5203.4 5248.9 5262.8 5238.3 5261.1 5272.8 5284.4 5285.4 5118.8 5145.2 5157.2 5199.1 5203.1 4608.6 5034.5 5142.9 5171.1 5217.1 5059.8 5352.6 5615.4 5680.7 5692.0 6168.9 6181.3 6183.3 6194.2 6201.5 5997.1 6095.8 6112.7 6113.6 6116.9 5721.9 5785.3 5796.2 5797.9 5820.1 6113.3 6134.7 6140.6 6148.6 6169.5 5942.3 6015.3 6058.6 6081.5 6125.1 6157.3 6158.1 6158.5 6159.3 6168.9 5758.6 5936.7 6094.6 6097.6 6139.8 6101.8 6124.3 6131.3 6161.4 6188.4 4899.2 4928.1 4949.5 4949.7 4950.3 4441.2 4448.8 4451.2 4451.6 4477.9 4483.9 4686.9 5485.4 5620.5 5682.4 5463.3 5769.1 6207.3 6215.5 6226.3 5691.8 5723.9 5736.6 5736.9 5771.7 5267.2 5303.9 5315.4 5361.4 5380.9 6136.5 6159.9 6182.6 6188.3 6236.4 6105.6 6175.8 6196.4 6196.7 6225.1 6125.4 6243.8 6257.8 6259.9 6260.1 5077.5 6057.8 6122.6 6131.2 6144.7 6132.5 6138.4 6139.3 6178.3 6196.4 5566.4 6050.5 6052.0 6122.7 6125.2 6114.4 6115.4 6117.3 6123.4 6130.3 5376.8 5717.5 5829.8 5941.3 5964.5 5990.5 5992.1 6048.9 6059.0 6127.5 5865.8 5913.7 5917.2 5920.0 5960.9 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | [#1] ext.html Cycles:180 Average:71849.87 Median:36921.22 stddev:170062.61 (460.6%) stddev-sans-first:170455.17 20:58:25 INFO - PROCESS | 1668 | Values: 441.7 473.8 513.7 520.0 552.3 26073.6 31924.7 38689.4 39827.8 39892.0 41149.6 41713.0 41815.8 41845.6 41892.7 9159.0 9323.8 9329.2 9338.3 9344.3 8384.9 8596.1 9165.5 9521.8 10651.3 11176.4 11179.0 11225.7 11229.8 11244.8 11173.7 11197.9 11218.0 11275.4 11288.7 11005.9 11105.7 11199.2 11201.3 11218.6 39022.4 39348.0 39360.4 39420.0 39426.8 2769.6 2948.0 3228.2 3556.8 3709.6 26454.3 27184.5 30115.4 30424.8 30562.8 3074.9 3115.9 3142.9 3251.3 3257.1 431932.0 441660.0 443568.4 444690.0 448942.8 55583.0 56928.1 61029.0 61412.0 61467.1 53826.7 61853.0 62012.0 62246.7 62981.0 15587.7 16061.5 16081.0 16094.5 16122.7 10648.7 12174.4 12273.1 12306.3 12355.5 15393.9 15614.5 16735.3 17315.7 17874.9 6261.2 6264.5 6272.8 6337.6 6344.7 739.0 887.0 922.7 975.0 1094.7 29246.0 29468.7 29925.4 30120.0 30344.7 925.3 1033.8 1037.5 1046.1 1106.6 15191.8 15216.5 15331.0 15634.1 15656.5 185046.0 877457.0 882086.0 895662.0 897999.0 56405.7 56974.7 57026.7 57065.0 57083.4 1214.7 1306.3 1317.2 1339.6 1352.5 30073.7 30212.0 30391.4 30690.5 30845.0 49885.0 49904.5 50251.2 50305.5 50490.0 564871.8 599226.0 605958.0 608202.0 609675.0 22859.0 25253.9 25497.1 25577.9 25592.7 32522.8 38874.2 45179.8 45802.3 47613.0 37300.8 37430.6 37851.8 38914.6 38962.0 37477.4 37671.8 38211.8 38809.2 38925.4 36460.5 36785.8 37056.6 37375.9 37622.8 32381.9 33596.7 34192.8 34233.1 34365.0 20413.4 24797.8 25074.7 25094.9 25269.9 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | [#2] jquery.html Cycles:180 Average:33934.21 Median:372.22 stddev:93115.01 (25015.9%) stddev-sans-first:93342.10 20:58:25 INFO - PROCESS | 1668 | Values: 362.5 369.7 374.8 376.2 394.0 18627.6 19010.4 19394.6 19608.9 19838.9 11323.0 11715.2 11746.1 11768.2 11813.2 5928.4 6547.6 6606.7 6626.9 6720.4 4484.9 6540.4 6590.9 7255.5 7647.7 7344.1 7720.9 7734.1 7737.1 7745.7 7409.9 7544.7 7546.5 7554.5 7569.8 6664.8 7194.6 7372.6 7384.4 7393.1 26797.9 27238.2 27546.6 27608.9 27764.9 2163.8 2385.5 2389.3 2440.2 2509.4 13506.5 15308.2 16311.1 16531.7 16823.9 1967.9 1979.9 1983.4 2015.6 2018.8 133926.0 147699.0 151700.0 151856.5 152055.0 9242.5 9854.8 9890.1 9903.6 21193.5 12274.8 12305.8 12333.9 12340.6 12392.4 7787.5 7862.1 7920.6 7957.6 8045.7 8144.9 8156.4 8291.6 8324.5 8331.8 3760.2 3852.7 3865.6 3867.5 3925.1 1772.8 1822.0 1824.4 1894.6 1908.8 855.5 860.2 913.1 917.3 938.7 18866.3 19348.9 20100.9 20185.4 20228.4 839.5 864.8 907.3 915.5 944.3 10265.5 10667.4 10675.5 10695.4 10712.8 416959.0 449011.3 604419.0 612268.9 626143.3 100371.0 132674.3 135491.0 136446.3 138757.0 906.2 919.6 930.3 937.5 946.1 12871.9 13880.0 17879.8 17936.9 18358.6 36854.8 48276.8 54593.7 55266.7 56846.4 72024.6 73267.6 73718.3 74619.0 74656.6 7590.4 7828.5 8016.6 8087.2 8206.8 8961.1 11546.6 12180.0 12553.7 12652.9 10351.5 10386.7 10943.4 10982.9 11092.0 10486.4 10578.3 10803.9 10946.7 10947.8 10621.0 10691.5 11092.3 11093.4 11180.7 7788.4 7922.3 7982.4 8020.1 8025.8 8895.4 9627.8 10037.9 10964.9 10968.0 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | [#3] mootools.html Cycles:180 Average:5290.38 Median:5132.56 stddev:2892.92 (56.4%) stddev-sans-first:2886.64 20:58:25 INFO - PROCESS | 1668 | Values: 1451.2 1486.1 1504.0 1699.8 1706.2 7396.3 7692.5 7877.6 8316.2 8325.3 7768.8 8088.8 8089.0 8232.8 8292.3 2294.3 2326.6 2327.9 2336.3 2345.4 2322.5 2341.9 2361.6 2362.9 2366.2 2300.4 2308.5 2324.3 2333.5 2336.1 1914.2 2157.4 2288.6 2307.8 2311.0 1717.0 1777.7 1982.9 2064.3 2327.2 6600.7 7691.2 8169.0 8309.9 8317.8 4972.7 5132.2 5139.7 5142.4 5143.4 2941.8 3154.9 3209.0 3341.3 3400.4 904.5 922.2 1045.0 1053.8 1065.0 10621.6 10699.7 10728.1 10729.5 10762.7 4364.4 4611.4 4631.8 4665.1 4679.6 7858.4 7917.6 9217.3 9276.4 9306.2 5132.9 5135.6 5145.1 5150.8 5154.6 4059.4 4625.5 4709.0 4709.3 4816.7 3378.3 3549.4 3692.3 3775.4 3843.2 1247.4 1258.9 1262.6 1262.8 1268.1 854.0 863.2 870.7 889.6 911.0 4713.7 5607.6 5676.8 5680.3 5935.2 841.4 860.4 898.8 920.6 934.8 3391.8 3552.7 3720.6 3739.7 3816.2 8889.3 10084.2 10100.7 10106.3 10169.0 9944.0 10057.6 10058.7 10098.7 10150.8 7383.9 7756.5 7919.9 7941.2 7941.5 4769.5 5074.8 5337.5 5520.7 5554.1 6781.5 9050.7 9097.7 9098.1 9129.5 7591.4 8190.6 9084.4 9218.2 9250.6 6482.7 6743.7 6807.0 7019.6 7138.3 7635.9 7800.8 7818.8 7829.2 7852.3 6866.4 7006.4 7013.8 7057.7 7112.7 6907.0 6927.5 6957.4 6968.6 7022.6 6368.7 6551.7 6622.4 6670.2 6672.3 4729.5 4787.0 4802.0 4842.4 4888.1 4649.3 4686.0 4715.7 4727.3 4890.1 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | [#4] prototype.html Cycles:180 Average:4436.90 Median:4877.51 stddev:1408.31 (28.9%) stddev-sans-first:1377.30 20:58:25 INFO - PROCESS | 1668 | Values: 282.4 301.7 305.7 313.1 315.0 4588.4 4632.3 4686.6 4763.5 4836.5 4675.9 5187.4 5220.2 5220.3 5244.8 3517.8 4035.0 4038.2 4053.1 4054.7 3057.6 3889.6 4050.9 4058.9 4073.9 3467.9 3647.4 3867.2 3965.4 3988.5 3161.8 3961.3 3975.3 3998.4 4027.2 2829.9 2993.4 3495.4 3521.5 3523.8 4119.6 4478.7 4832.4 5024.8 5072.6 4151.6 4519.0 4589.2 4601.8 4634.5 4233.3 4619.7 5557.6 5562.1 5611.7 3237.9 3256.9 3655.6 3799.4 3884.8 5721.0 5788.7 5799.0 5820.1 5830.3 3916.5 3930.9 3935.4 3954.4 3965.4 3648.6 3849.5 3856.7 3917.1 4015.1 4253.6 4418.7 4508.5 4570.2 4579.2 5676.5 5685.4 5687.9 5689.3 5705.7 1187.3 1213.6 1242.4 1281.2 1287.5 741.7 776.7 862.8 863.8 875.4 3871.9 4086.9 4170.6 4234.4 4881.6 5002.3 5092.5 5379.6 5480.6 5512.0 4135.9 4580.5 4873.4 4899.4 4915.9 2653.7 2833.5 2857.8 2858.3 2872.6 5003.5 5580.3 5657.6 5658.4 5739.0 4897.2 5883.8 5964.6 5999.7 6011.2 5024.0 5313.7 5955.3 6058.3 6101.7 5064.7 5420.0 5619.5 5817.6 5864.4 5090.2 5462.1 5746.9 5759.9 5777.2 5726.4 6019.2 6039.5 6047.2 6055.7 3264.8 3400.9 3793.1 3870.0 3883.9 5230.3 5311.9 5842.8 5862.3 5865.7 5276.7 5643.0 5651.4 5658.9 5676.5 4640.7 4940.3 5587.6 5597.2 5644.9 5053.6 5284.0 5495.9 5541.0 5573.3 4567.3 4913.9 5625.7 5663.8 5683.0 4583.8 5476.8 5537.3 5558.9 5561.8 20:58:25 INFO - PROCESS | 1668 | 20:58:25 INFO - PROCESS | 1668 | [#5] yui.html Cycles:180 Average:19886.51 Median:25395.16 stddev:29446.50 (116.0%) stddev-sans-first:29493.88 20:58:25 INFO - PROCESS | 1668 | Values: 702.4 704.7 728.2 753.4 761.5 11424.8 11753.2 12464.3 12649.4 12666.2 13062.3 13197.9 13198.1 13204.8 13310.9 655.1 682.1 816.8 826.0 843.6 852.4 852.5 854.2 855.4 858.4 893.2 893.8 896.3 897.9 900.5 881.9 893.2 893.5 893.8 896.1 660.4 661.2 665.3 669.7 672.0 13374.6 13507.9 13533.1 13576.6 13964.9 13479.6 13554.5 13567.6 13592.4 13841.3 9143.4 9302.5 9358.5 9439.5 9657.0 3257.9 3465.4 3579.8 3590.4 3594.7 89829.0 89868.0 90059.5 90442.0 93335.5 10501.6 10507.3 10526.2 10677.4 10898.1 18266.7 20817.3 20840.0 20857.9 20927.8 7608.3 7611.9 7623.2 7670.9 7819.5 4220.7 4270.7 4276.8 4307.7 4323.3 2965.8 3322.5 3416.7 3417.2 3652.2 639.7 640.9 646.4 663.0 692.0 572.4 614.8 633.5 646.8 656.9 8741.2 9023.8 9030.7 9035.8 9104.4 625.3 644.6 657.8 663.2 669.8 4213.3 4289.3 4429.6 4479.7 4502.6 106023.5 108825.5 110956.0 112719.4 112859.4 65898.3 92778.5 99069.1 100192.0 101522.0 46525.5 81880.6 82771.0 85554.0 85977.0 9295.1 9633.4 9715.3 9730.9 9754.5 46287.8 46662.8 53010.9 53158.7 55618.7 55447.4 69073.7 73124.6 74000.0 77383.6 11850.8 12310.3 12608.7 12657.7 12817.4 10405.8 12191.8 12535.7 12962.6 13407.4 10705.8 11873.9 13078.5 13405.4 13457.7 12834.7 13064.3 13148.7 13418.9 13624.4 12765.2 12773.6 12775.6 12879.7 12970.3 10197.4 10312.5 10395.8 10448.7 10996.6 9007.6 10721.2 10879.7 10947.5 10982.9 20:58:25 INFO - PROCESS | 1668 | -------- Summary: end -------- 20:58:25 INFO - PROCESS | 1668 | 20:58:26 INFO - TEST-INFO | 1668: exit 0 20:58:26 INFO - TEST-OK | dromaeo_css | took 1116184ms 20:58:26 INFO - TEST-START | kraken 20:58:26 INFO - operating with platform_type : mac_ 20:58:26 INFO - Initialising browser for kraken test... 20:58:26 INFO - TEST-INFO | started process 1675 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp7ypEil/profile http://localhost:49246/getInfo.html) 20:58:29 INFO - PROCESS | 1675 | 2016-11-27 20:58:29.859 plugin-container[1676:13067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 20:58:29 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:29 INFO - PROCESS | 1675 | 2016-11-27 20:58:29.862 plugin-container[1676:13067] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 20:58:29 INFO - PROCESS | 1675 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:29 INFO - PROCESS | 1675 | 2016-11-27 20:58:29.862 plugin-container[1676:13067] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:58:29 INFO - PROCESS | 1675 | __metrics Screen width/height:1600/1200 20:58:29 INFO - PROCESS | 1675 | colorDepth:24 20:58:29 INFO - PROCESS | 1675 | Browser inner width/height: 1024/689 20:58:29 INFO - PROCESS | 1675 | __metrics 20:58:30 ERROR - PROCESS | 1675 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3198: TypeError: this.tabs is undefined 20:58:30 INFO - TEST-INFO | 1675: exit 0 20:58:30 INFO - Browser initialized. 20:58:30 INFO - Running cycle 1/1 for kraken test... 20:58:30 INFO - TEST-INFO | started process 1677 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/T/tmp7ypEil/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:58:33 INFO - PROCESS | 1677 | 2016-11-27 20:58:33.560 plugin-container[1678:13184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873b, name = 'com.apple.tsm.portname' 20:58:33 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:33 INFO - PROCESS | 1677 | 2016-11-27 20:58:33.561 plugin-container[1678:13184] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4707, name = 'com.apple.CFPasteboardClient' 20:58:33 INFO - PROCESS | 1677 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:58:33 INFO - PROCESS | 1677 | 2016-11-27 20:58:33.561 plugin-container[1678:13184] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:58:43 INFO - PROCESS | 1677 | RSS: Main: 241872896 20:58:43 INFO - PROCESS | 1677 | 20:58:46 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection) 20:58:47 INFO - PROCESS | 1677 | RSS: Main: 254066688 20:58:47 INFO - PROCESS | 1677 | 20:58:49 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-dft) 20:58:50 INFO - PROCESS | 1677 | RSS: Main: 261685248 20:58:50 INFO - PROCESS | 1677 | 20:58:53 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-fft) 20:58:54 INFO - PROCESS | 1677 | RSS: Main: 264237056 20:58:54 INFO - PROCESS | 1677 | 20:58:56 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator) 20:58:57 INFO - PROCESS | 1677 | RSS: Main: 265424896 20:58:57 INFO - PROCESS | 1677 | 20:58:58 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:58:59 INFO - PROCESS | 1677 | RSS: Main: 267112448 20:58:59 INFO - PROCESS | 1677 | 20:59:02 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom) 20:59:03 INFO - PROCESS | 1677 | RSS: Main: 272457728 20:59:03 INFO - PROCESS | 1677 | 20:59:06 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate) 20:59:06 INFO - PROCESS | 1677 | RSS: Main: 271847424 20:59:06 INFO - PROCESS | 1677 | 20:59:09 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial) 20:59:10 INFO - PROCESS | 1677 | RSS: Main: 272080896 20:59:10 INFO - PROCESS | 1677 | 20:59:11 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:59:12 INFO - PROCESS | 1677 | RSS: Main: 271011840 20:59:12 INFO - PROCESS | 1677 | 20:59:14 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:59:15 INFO - PROCESS | 1677 | RSS: Main: 271044608 20:59:15 INFO - PROCESS | 1677 | 20:59:17 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:59:18 INFO - PROCESS | 1677 | RSS: Main: 271007744 20:59:18 INFO - PROCESS | 1677 | 20:59:21 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:59:22 INFO - PROCESS | 1677 | RSS: Main: 271351808 20:59:22 INFO - PROCESS | 1677 | 20:59:25 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:59:25 INFO - PROCESS | 1677 | RSS: Main: 271421440 20:59:25 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | Cycle 1(1): loaded http://localhost:49246/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49246/tests/kraken/driver.html?testName=ai-astar) 20:59:27 INFO - PROCESS | 1677 | RSS: Main: 273199104 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | __start_tp_report 20:59:27 INFO - PROCESS | 1677 | _x_x_mozilla_page_load 20:59:27 INFO - PROCESS | 1677 | _x_x_mozilla_page_load_details 20:59:27 INFO - PROCESS | 1677 | |i|pagename|runs| 20:59:27 INFO - PROCESS | 1677 | |0;ai-astar;107;117;118;118;118;118;119;118;119;120 20:59:27 INFO - PROCESS | 1677 | |1;audio-beat-detection;146;117;118;152;116;117;115;118;170;154 20:59:27 INFO - PROCESS | 1677 | |2;audio-dft;202;210;234;271;201;197;198;223;254;193 20:59:27 INFO - PROCESS | 1677 | |3;audio-fft;82;59;57;85;62;59;60;58;70;70 20:59:27 INFO - PROCESS | 1677 | |4;audio-oscillator;80;69;84;70;68;71;82;68;71;81 20:59:27 INFO - PROCESS | 1677 | |5;imaging-gaussian-blur;90;88;93;87;85;129;125;88;94;114 20:59:27 INFO - PROCESS | 1677 | |6;imaging-darkroom;97;96;96;97;96;96;106;126;96;97 20:59:27 INFO - PROCESS | 1677 | |7;imaging-desaturate;94;94;116;120;92;112;105;91;93;86 20:59:27 INFO - PROCESS | 1677 | |8;json-parse-financial;58;57;56;57;58;59;58;58;57;57 20:59:27 INFO - PROCESS | 1677 | |9;json-stringify-tinderbox;68;76;76;74;95;91;102;105;102;70 20:59:27 INFO - PROCESS | 1677 | |10;stanford-crypto-aes;87;80;117;113;109;89;89;88;88;88 20:59:27 INFO - PROCESS | 1677 | |11;stanford-crypto-ccm;149;194;164;155;150;150;153;159;193;163 20:59:27 INFO - PROCESS | 1677 | |12;stanford-crypto-pbkdf2;179;177;174;177;175;176;177;210;203;177 20:59:27 INFO - PROCESS | 1677 | |13;stanford-crypto-sha256-iterative;67;71;79;78;85;85;71;66;81;64 20:59:27 INFO - PROCESS | 1677 | __end_tp_report 20:59:27 INFO - PROCESS | 1677 | __start_cc_report 20:59:27 INFO - PROCESS | 1677 | _x_x_mozilla_cycle_collect,950 20:59:27 INFO - PROCESS | 1677 | __end_cc_report 20:59:27 INFO - PROCESS | 1677 | __startTimestamp1480309167726__endTimestamp 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | ------- Summary: start ------- 20:59:27 INFO - PROCESS | 1677 | Number of tests: 14 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#0] ai-astar Cycles:10 Average:117.20 Median:118.00 stddev:3.68 (3.1%) stddev-sans-first:0.87 20:59:27 INFO - PROCESS | 1677 | Values: 107.0 117.0 118.0 118.0 118.0 118.0 119.0 118.0 119.0 120.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#1] audio-beat-detection Cycles:10 Average:132.30 Median:132.00 stddev:20.84 (15.8%) stddev-sans-first:21.51 20:59:27 INFO - PROCESS | 1677 | Values: 146.0 117.0 118.0 152.0 116.0 117.0 115.0 118.0 170.0 154.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#2] audio-dft Cycles:10 Average:218.30 Median:216.50 stddev:26.79 (12.4%) stddev-sans-first:27.76 20:59:27 INFO - PROCESS | 1677 | Values: 202.0 210.0 234.0 271.0 201.0 197.0 198.0 223.0 254.0 193.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#3] audio-fft Cycles:10 Average:66.20 Median:66.00 stddev:10.24 (15.5%) stddev-sans-first:9.13 20:59:27 INFO - PROCESS | 1677 | Values: 82.0 59.0 57.0 85.0 62.0 59.0 60.0 58.0 70.0 70.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#4] audio-oscillator Cycles:10 Average:74.40 Median:75.50 stddev:6.48 (8.6%) stddev-sans-first:6.55 20:59:27 INFO - PROCESS | 1677 | Values: 80.0 69.0 84.0 70.0 68.0 71.0 82.0 68.0 71.0 81.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#5] imaging-gaussian-blur Cycles:10 Average:99.30 Median:88.00 stddev:16.75 (19.0%) stddev-sans-first:17.42 20:59:27 INFO - PROCESS | 1677 | Values: 90.0 88.0 93.0 87.0 85.0 129.0 125.0 88.0 94.0 114.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:96.00 stddev:9.53 (9.9%) stddev-sans-first:10.04 20:59:27 INFO - PROCESS | 1677 | Values: 97.0 96.0 96.0 97.0 96.0 96.0 106.0 126.0 96.0 97.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:91.50 stddev:11.95 (13.1%) stddev-sans-first:12.46 20:59:27 INFO - PROCESS | 1677 | Values: 94.0 94.0 116.0 120.0 92.0 112.0 105.0 91.0 93.0 86.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:58.00 stddev:0.85 (1.5%) stddev-sans-first:0.88 20:59:27 INFO - PROCESS | 1677 | Values: 58.0 57.0 56.0 57.0 58.0 59.0 58.0 58.0 57.0 57.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#9] json-stringify-tinderbox Cycles:10 Average:85.90 Median:75.00 stddev:14.54 (19.4%) stddev-sans-first:13.91 20:59:27 INFO - PROCESS | 1677 | Values: 68.0 76.0 76.0 74.0 95.0 91.0 102.0 105.0 102.0 70.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:88.00 stddev:12.96 (14.7%) stddev-sans-first:13.44 20:59:27 INFO - PROCESS | 1677 | Values: 87.0 80.0 117.0 113.0 109.0 89.0 89.0 88.0 88.0 88.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#11] stanford-crypto-ccm Cycles:10 Average:163.00 Median:161.00 stddev:16.92 (10.5%) stddev-sans-first:17.17 20:59:27 INFO - PROCESS | 1677 | Values: 149.0 194.0 164.0 155.0 150.0 150.0 153.0 159.0 193.0 163.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.50 Median:177.00 stddev:12.83 (7.2%) stddev-sans-first:13.54 20:59:27 INFO - PROCESS | 1677 | Values: 179.0 177.0 174.0 177.0 175.0 176.0 177.0 210.0 203.0 177.0 20:59:27 INFO - PROCESS | 1677 | 20:59:27 INFO - PROCESS | 1677 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.70 Median:78.50 stddev:7.87 (10.0%) stddev-sans-first:7.84 20:59:27 INFO - PROCESS | 1677 | Values: 67.0 71.0 79.0 78.0 85.0 85.0 71.0 66.0 81.0 64.0 20:59:27 INFO - PROCESS | 1677 | -------- Summary: end -------- 20:59:27 INFO - PROCESS | 1677 | 20:59:28 INFO - TEST-INFO | 1677: exit 0 20:59:28 INFO - TEST-OK | kraken | took 62011ms 20:59:28 INFO - SUITE-END | took 1178s 20:59:28 INFO - Completed test suite (00:19:38) 20:59:28 INFO - javascript benchmark 20:59:28 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7101.451358942287, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3296.571445712057, 3321.518379834924, 3359.5128706337573, 3361.2437201629637, 3379.6789305016046, 4503.256962601167, 5077.41609714882, 5143.765496280894, 5576.498115169634, 5633.267675202218, 5570.080936645459, 5615.691136987464, 5646.096624540074, 5653.1520271959125, 5689.089745640698, 5174.611904107188, 5196.272521846926, 5200.50595193457, 5235.476452354772, 5242.973785131088, 5131.666441681279, 5180.715060671661, 5203.427622961472, 5248.947510524884, 5262.789488420478, 5238.319018527605, 5261.053010458116, 5272.787258930448, 5284.392294886099, 5285.365756109272, 5118.769655365518, 5145.228215767665, 5157.226416037587, 5199.1161502544655, 5203.089459344599, 4608.631309495232, 5034.496550344973, 5142.919986802776, 5171.095058364771, 5217.139172036609, 5059.83623766532, 5352.625316227865, 5615.438456154393, 5680.6591604503865, 5691.971540142315, 6168.938310616882, 6181.320054794014, 6183.288921773964, 6194.163787888622, 6201.503879689614, 5997.130416089686, 6095.847603809941, 6112.724927378279, 6113.572049956505, 6116.908246376308, 5721.885562288814, 5785.334686511063, 5796.246487956539, 5797.88404231913, 5820.097884827858, 6113.263474096151, 6134.681512609229, 6140.600860944024, 6148.569600127948, 6169.475594574422, 5942.3166335871065, 6015.30823955242, 6058.606190597597, 6081.543884208702, 6125.111858780452, 6157.291911430153, 6158.076288556752, 6158.476529494954, 6159.29168145669, 6168.907466388007, 5758.62568933018, 5936.7328470218945, 6094.603850749687, 6097.603655762361, 6139.84650383744, 6101.786437474667, 6124.258880972529, 6131.325554189125, 6161.353057928853, 6188.381161883775, 4899.167141585939, 4928.088303663833, 4949.480304567968, 4949.7030178189425, 4950.2822090796635, 4441.244988351988, 4448.755318457451, 4451.154280686659, 4451.577100175478, 4477.932831007537, 4483.910321793611, 4686.859394218179, 5485.369182543979, 5620.494155526022, 5682.374938756816, 5463.289772329651, 5769.134629805562, 6207.317195108534, 6215.471684906744, 6226.25284965798, 5691.77232910679, 5723.942760572341, 5736.598438109292, 5736.942630573724, 5771.6537007778825, 5267.236250743621, 5303.867403314871, 5315.362156541162, 5361.383440904268, 5380.946190538123, 6136.4578081083455, 6159.907601385983, 6182.567220294716, 6188.288346840171, 6236.407541283571, 6105.572609917263, 6175.84560385994, 6196.442320191204, 6196.659183744938, 6225.128482012612, 6125.356837532173, 6243.750249989949, 6257.812265632038, 6259.906101408574, 6260.092286618506, 5077.517635824591, 6057.757689692539, 6122.602030867979, 6131.172291740558, 6144.723487443165, 6132.540059495466, 6138.357744607992, 6139.308154112039, 6178.289496707936, 6196.411340922605, 5566.415526369673, 6050.515958723379, 6052.0, 6122.7244773984385, 6125.173101631401, 6114.38856114385, 6115.449609535163, 6117.265928088658, 6123.418275263843, 6130.325664177026, 5376.838694839161, 5717.514011309086, 5829.766809327749, 5941.287045554561, 5964.493018093337, 5990.4908082813445, 5992.101184822312, 6048.879022419439, 6059.030814985059, 6127.540434467343, 5865.794697185578, 5913.733881975236, 5917.201177841109, 5920.0, 5960.850978725566], "value": 5602.475725915888, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.6577152706654, 473.8388947757763, 513.7431284357821, 519.9584033277338, 552.3096129837705, 26073.608895866553, 31924.68075319246, 38689.419658705105, 39827.80086099565, 39892.0, 41149.58850411495, 41713.0, 41815.79092104543, 41845.58154418455, 41892.743217703515, 9159.038300978385, 9323.813523729521, 9329.160375566198, 9338.346315757866, 9344.345895787297, 8384.874226886572, 8596.140385961417, 9165.495897725623, 9521.80956380872, 10651.307665001788, 11176.385298808562, 11179.0, 11225.719357016058, 11229.831552526719, 11244.775104497905, 11173.7206569836, 11197.888021119767, 11218.0, 11275.43622818864, 11288.717782055432, 11005.889941100566, 11105.666829995112, 11199.160062995226, 11201.27191732543, 11218.607348742755, 39022.414663780066, 39348.016299592455, 39360.409593856115, 39420.0, 39426.802865985774, 2769.556870900646, 2947.985260073686, 3228.2252259457796, 3556.768810027366, 3709.5733990591157, 26454.338641533923, 27184.45631087387, 30115.397692046256, 30424.8478757607, 30562.777488900418, 3074.8923787667322, 3115.875364985398, 3142.874285028596, 3251.2522119912314, 3257.0717345556404, 431932.0, 441660.0, 443568.385896844, 444690.0, 448942.75528622477, 55583.0, 56928.146077808866, 61029.0, 61412.0, 61467.07799383013, 53826.7308663462, 61853.0, 62012.0, 62246.688766555875, 62981.0, 15587.688246235239, 16061.518154455385, 16081.0, 16094.517164485083, 16122.67754644924, 10648.680539583826, 12174.39128043612, 12273.140880138482, 12306.261624302391, 12355.505779768708, 15393.923030384776, 15614.45349412765, 16735.326467353167, 17315.65368692619, 17874.92750434978, 6261.24865016201, 6264.5301602380005, 6272.843178920472, 6337.556371054075, 6344.651044192614, 739.0, 886.9755432475501, 922.7231830450838, 974.9853752193723, 1094.655183617158, 29246.0, 29468.705312947026, 29925.401491970475, 30120.0, 30344.696553034188, 925.3013974449248, 1033.7570670892335, 1037.5175543372368, 1046.0637729232294, 1106.618216715232, 15191.848081519043, 15216.543503694907, 15331.003484773453, 15634.140122293384, 15656.4520241791, 185046.0, 877457.0, 882086.0, 895662.0, 897999.0, 56405.717971410704, 56974.71512642493, 57026.714866426235, 57065.0, 57083.42916570864, 1214.6720385495867, 1306.2880730001982, 1317.236003118189, 1339.6449940765706, 1352.5333759852836, 30073.66647726668, 30212.0, 30391.392172156877, 30690.539641905834, 30845.0, 49885.002299954525, 49904.500954989984, 50251.24623130729, 50305.49694503008, 50490.0, 564871.8102551869, 599226.0038699779, 605958.0, 608202.0, 609675.0, 22858.97134628979, 25253.87373063123, 25497.107601233867, 25577.872110640074, 25592.744072559035, 32522.83738581292, 38874.22251555009, 45179.77410112928, 45802.31296530488, 47613.0, 37300.81349593234, 37430.62569374272, 37851.81074094722, 38914.6108538911, 38962.0, 37477.4378384319, 37671.81164094162, 38211.80894095512, 38809.2238155241, 38925.416118757676, 36460.45309320415, 36785.81607092055, 37056.629433706396, 37375.878723638336, 37622.8118859404, 32381.866634667673, 33596.66403336033, 34192.82903585466, 34233.144171395914, 34365.0, 20413.38759837207, 24797.752022479544, 25074.74925250724, 25094.87073081679, 25269.87365063163], "value": 19609.97160101724, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.4726023635609, 369.68022660398753, 374.7657713928795, 376.1630372421361, 394.0090671960019, 18627.627447451043, 19010.42968710939, 19394.612107757835, 19608.901955490204, 19838.908860012692, 11323.0, 11715.238509496898, 11746.119041071912, 11768.176227664046, 11813.173077884552, 5928.436798504146, 6547.57440766351, 6606.735730570771, 6626.8674626507445, 6720.428763555104, 4484.887877803065, 6540.411362977331, 6590.901136482933, 7255.528390654617, 7647.655855486517, 7344.11870575529, 7720.884186737204, 7734.071911370644, 7737.148913619498, 7745.65144568493, 7409.88885166723, 7544.660490277895, 7546.54720716756, 7554.546727196331, 7569.810754731148, 6664.766733164316, 7194.64026798658, 7372.631368431611, 7384.409247260206, 7393.149787774421, 26797.866010669823, 27238.182854514398, 27546.5868011978, 27608.861955690292, 27764.861175694194, 2163.7619861815188, 2385.522895420923, 2389.295157928398, 2440.1947357372023, 2509.4102885821817, 13506.527271545547, 15308.234588270654, 16311.124220714531, 16531.66936661272, 16823.91588042064, 1967.9212831486723, 1979.910904009323, 1983.4248068060244, 2015.6371853066441, 2018.8082132197417, 133926.0, 147699.0, 151700.0, 151856.48810800372, 152055.0, 9242.491662958602, 9854.802903942025, 9890.060444257784, 9903.554340054714, 21193.470163245733, 12274.815877761841, 12305.815412768816, 12333.93833030847, 12340.568080117153, 12392.442340094718, 7787.493812902143, 7862.056553213651, 7920.643571039317, 7957.641906114239, 8045.678172873019, 8144.877826832602, 8156.388270879707, 8291.58542072894, 8324.542150181676, 8331.791705207297, 3760.2479504099288, 3852.691784657277, 3865.594112618191, 3867.477890484805, 3925.1364699766004, 1772.822717728217, 1822.026362073399, 1824.3979486769335, 1894.5547796267865, 1908.8186622270862, 855.5209082913589, 860.165639329849, 913.0869130869131, 917.2936838634348, 938.7183844846519, 18866.3396781112, 19348.903255483634, 20100.899495502428, 20185.394438166877, 20228.393148205578, 839.5340585974842, 864.831357885219, 907.3195103672246, 915.455304094063, 944.3389627260945, 10265.486725663686, 10667.413292269, 10675.519601617945, 10695.411752353695, 10712.839307410395, 416959.0, 449011.2648310278, 604419.0, 612268.9386553039, 626143.3464163342, 100371.0, 132674.33662831623, 135491.0, 136446.31776841052, 138757.0, 906.1934877958622, 919.5999740113073, 930.283681565191, 937.4609599480326, 946.1248345280588, 12871.935640321739, 13880.0, 17879.821201787814, 17936.923784572446, 18358.632827343645, 36854.8157259212, 48276.758616206695, 54593.727031364586, 55266.72366638141, 56846.44191011332, 72024.63987680028, 73267.63366183135, 73718.26281737328, 74619.0, 74656.62671686607, 7590.430717696085, 7828.491148075357, 8016.599170041358, 8087.191280871866, 8206.794830129294, 8961.059088795844, 11546.59586914454, 12180.0, 12553.686157846127, 12652.936735316265, 10351.534180962026, 10386.740331491774, 10943.398113103856, 10982.890171098186, 11092.001719845253, 10486.37081775096, 10578.259521833397, 10803.945980270048, 10946.726331841768, 10947.781044379228, 10621.0, 10691.465426728788, 11092.279001864854, 11093.44532773342, 11180.720481988015, 7788.403377307752, 7922.326602238874, 7982.401319901147, 8020.117787043304, 8025.8394832104195, 8895.421797583136, 9627.79652543432, 10037.899621003697, 10964.890351096386, 10968.0], "value": 9545.695408629877, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1451.1510766201777, 1486.0563542150735, 1504.0299007140395, 1699.8215187405315, 1706.2236682309551, 7396.334329910307, 7692.499987500809, 7877.645505952231, 8316.168383161688, 8325.333973282139, 7768.844623107534, 8088.797780055502, 8089.0, 8232.753017409472, 8292.336613070955, 2294.288770481152, 2326.581215381231, 2327.94180145496, 2336.2874323331375, 2345.44881952741, 2322.4890524084676, 2341.8594884306913, 2361.574916515035, 2362.9054837806466, 2366.230974933145, 2300.4478925058033, 2308.4575124845646, 2324.2794733632627, 2333.5216280662507, 2336.1122773346187, 1914.2343062774862, 2157.4067131538955, 2288.5880541502524, 2307.7807608277185, 2311.0178174276034, 1716.9698181091371, 1777.742227377036, 1982.9107690153978, 2064.3394113883564, 2327.1622216002224, 6600.702968366435, 7691.153973062958, 8168.959155204246, 8309.875351869727, 8317.792055198608, 4972.701637901738, 5132.230165475171, 5139.665921715114, 5142.3829140503, 5143.3570803649545, 2941.7646588272887, 3154.936901261962, 3209.0472174117144, 3341.2816244507303, 3400.4049291373913, 904.5477261369315, 922.2345453273766, 1044.993612264454, 1053.8155822731069, 1064.9563427840683, 10621.628243011535, 10699.679009629646, 10728.141748660088, 10729.463526823705, 10762.677119686421, 4364.41080454141, 4611.446626404853, 4631.791569379386, 4665.066986602693, 4679.555442232982, 7858.410619203582, 7917.564533950688, 9217.308701847254, 9276.350655454053, 9306.162445379949, 5132.897342053138, 5135.589152867761, 5145.073886700355, 5150.768215430315, 5154.639175257771, 4059.4316795648633, 4625.496713568104, 4709.0, 4709.270063140219, 4816.710997340171, 3378.307446973414, 3549.4498352755363, 3692.2800053989213, 3775.3581891078584, 3843.1545060086737, 1247.4074814463058, 1258.8992880569533, 1262.5959692898368, 1262.8421447319085, 1268.1440027981075, 853.9786505337293, 863.2058506174335, 870.6647940542937, 889.6174644902754, 911.0479548871446, 4713.670043097019, 5607.551395888319, 5676.829695109153, 5680.261565996394, 5935.228420305332, 841.3521588377038, 860.3547339495378, 898.7528429681889, 920.5857364186143, 934.845750451168, 3391.847366868497, 3552.685506362662, 3720.5535335759882, 3739.700823934079, 3816.160444702161, 8889.333300002396, 10084.193264538819, 10100.74748131288, 10106.343087699275, 10169.0, 9944.0, 10057.597696092074, 10058.748531286776, 10098.747531311628, 10150.796984060278, 7383.9261607384315, 7756.534607923542, 7919.920800792033, 7941.2058794121285, 7941.483803552751, 4769.451513075902, 5074.80742025631, 5337.466253374631, 5520.696361700145, 5554.111342185231, 6781.491388146008, 9050.732897394239, 9097.681581144629, 9098.090190980849, 9129.497877616795, 7591.3547348475995, 8190.590470476572, 9084.364094513321, 9218.170364667212, 9250.629974800933, 6482.70827812759, 6743.696533656095, 6806.965965170142, 7019.613921234381, 7138.286171382821, 7635.88546171819, 7800.765977020698, 7818.804529886798, 7829.217078292125, 7852.293293603603, 6866.382025617719, 7006.439484841098, 7013.824654383681, 7057.682404291922, 7112.715491380287, 6907.0, 6927.549709268881, 6957.443404527525, 6968.581885086911, 7022.613756243456, 6368.681565921778, 6551.737930482727, 6622.437092846969, 6670.232923213893, 6672.299408562031, 4729.456112546964, 4787.042591481787, 4801.95198048029, 4842.443119041355, 4888.095702295087, 4649.256119020941, 4685.953140468688, 4715.669903106749, 4727.267273572602, 4890.07088653169], "value": 4295.2451973423285, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [282.42808313165835, 301.6500859003555, 305.6683498404231, 313.0514540940948, 314.9889753858615, 4588.426446694163, 4632.305154226863, 4686.55477729616, 4763.523647635239, 4836.46798852126, 4675.88310292242, 5187.377514698242, 5220.164773636209, 5220.321358223435, 5244.763985620647, 3517.7537572369943, 4034.9798251008706, 4038.1519880825063, 4053.1083161704523, 4054.6958978076614, 3057.6330840299197, 3889.6499315061637, 4050.8784736457956, 4058.8782336529953, 4073.920411091048, 3467.930641387183, 3647.361711700455, 3867.168558759866, 3965.4448377227213, 3988.521377434712, 3161.762867784914, 3961.2869683456966, 3975.3241948868763, 3998.400239964014, 4027.154297597479, 2829.9104844634758, 2993.431248062883, 3495.4057810172076, 3521.506989021539, 3523.7709548879243, 4119.567445418218, 4478.731276123411, 4832.381152313979, 5024.773885175176, 5072.590519503244, 4151.605597468236, 4518.954810451919, 4589.2198326284615, 4601.792919318638, 4634.513376095529, 4233.264361611725, 4619.72281663098, 5557.6109672322955, 5562.108882979806, 5611.691356975405, 3237.9028629140926, 3256.902292931216, 3655.5796083450477, 3799.4490798834013, 3884.76691398517, 5720.971395143039, 5788.739506722208, 5798.971005144947, 5820.1269809528485, 5830.30036395631, 3916.5104361954754, 3930.941035884464, 3935.3900145477496, 3954.426608141803, 3965.385365268388, 3648.5770549485724, 3849.4995650565247, 3856.688725833174, 3917.138229589486, 4015.056461731491, 4253.574642535722, 4418.690691651618, 4508.526604706459, 4570.223062079454, 4579.198640237945, 5676.517496012802, 5685.403032681592, 5687.857803554862, 5689.345725241567, 5705.686187259741, 1187.2876274235562, 1213.5873802906876, 1242.403646249787, 1281.2127082719553, 1287.5300515311844, 741.6921977379435, 776.7087342246657, 862.7799911022774, 863.7797361672733, 875.444093000949, 3871.903202419962, 4086.8773936781945, 4170.561156401037, 4234.407182994383, 4881.609471242292, 5002.274670172877, 5092.516210959953, 5379.632961496929, 5480.561555075585, 5511.972440137773, 4135.855245066408, 4580.511333816523, 4873.415190177201, 4899.387576552931, 4915.926261106087, 2653.6550248467574, 2833.546632538784, 2857.8285302881895, 2858.3140046388717, 2872.5547540131315, 5003.4746351633285, 5580.2745643066955, 5657.575681823797, 5658.434156584309, 5738.971305143447, 4897.1674815281485, 5883.823485295448, 5964.582479226499, 5999.730012149376, 6011.248593925759, 5023.974880125649, 5313.707746073926, 5955.255593050869, 6058.303295121117, 6101.694915254308, 5064.721440320818, 5420.0, 5619.49424551776, 5817.563682723728, 5864.354920958776, 5090.2364645303505, 5462.098753705594, 5746.885062298647, 5759.884802304015, 5777.2200752897825, 5726.396608949501, 6019.22432882292, 6039.456448919618, 6047.218279591437, 6055.697215139314, 3264.8041117533025, 3400.8639654413546, 3793.1086194744767, 3870.0262865939135, 3883.8834834954996, 5230.320058392384, 5311.946880531145, 5842.795502157404, 5862.325832529144, 5865.680221950027, 5276.736163191748, 5642.971785141048, 5651.4348565143155, 5658.943410566007, 5676.545876329801, 4640.69835460694, 4940.308356830118, 5587.6088673794075, 5597.188407680945, 5644.887102258014, 5053.646244762831, 5284.0, 5495.862603434946, 5540.972295138498, 5573.303337082864, 4567.314902764612, 4913.926291105565, 5625.718714064362, 5663.830085097454, 5682.971585142048, 4583.760021979589, 5476.808311709071, 5537.28015358017, 5558.943800677858, 5561.805336813192], "value": 3987.5809428038788, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.4029574861368, 704.7462913351189, 728.2389902551832, 753.4273951796633, 761.4859969520576, 11424.828627570572, 11753.177277590572, 12464.314462704548, 12649.430775615096, 12666.17669851459, 13062.30190075289, 13197.868021319784, 13198.142120762168, 13204.801927971064, 13310.93344533276, 655.1221363373079, 682.1200651159998, 816.779469543223, 826.0335407573124, 843.5571325054334, 852.3862818770475, 852.4842470305468, 854.2226573817827, 855.3969451536652, 858.4076986879064, 893.2095095840195, 893.7810236492036, 896.3411892259185, 897.8563429851225, 900.5362238447204, 881.8589025755848, 893.2362829780574, 893.5353616119646, 893.8212357528488, 896.085992287866, 660.4386271669091, 661.2263651527724, 665.3180489997791, 669.7053296549502, 671.9899201511932, 13374.598762037058, 13507.86492135076, 13533.052686311961, 13576.59270221895, 13964.93017534916, 13479.595612131652, 13554.525591604344, 13567.59297221085, 13592.38834252461, 13841.30793460323, 9143.359964802466, 9302.534873256378, 9358.485283309416, 9439.480828554426, 9656.951715241448, 3257.9248847880144, 3465.42820434628, 3579.8210089495683, 3590.3716849551483, 3594.730395220343, 89829.0, 89868.0, 90059.54970225172, 90441.99999999935, 93335.53332233295, 10501.579936802595, 10507.264491485676, 10526.157907367546, 10677.359358438363, 10898.128149748001, 18266.725999110025, 20817.271395501386, 20839.958002099833, 20857.89571052135, 20927.79072209289, 7608.31525162738, 7611.856181703021, 7623.199564045695, 7670.884936725954, 7819.530828150329, 4220.683448741357, 4270.743755374688, 4276.807543660543, 4307.7415355078165, 4323.308270676676, 2965.8368789716774, 3322.4850148227065, 3416.7095796857047, 3417.231122997355, 3652.1782598915033, 639.6961443314389, 640.8914670733328, 646.3536463536464, 662.9767958121539, 691.9861602768017, 572.373251289837, 614.7510258345377, 633.499535367064, 646.7833275852643, 656.9441597464173, 8741.229182517573, 9023.774405639911, 9030.683926062557, 9035.819283614292, 9104.408213466104, 625.2809269340295, 644.6228956060663, 657.7500549791049, 663.1909070933453, 669.8158006548239, 4213.346931225665, 4289.292266775947, 4429.579189976947, 4479.731216127043, 4502.561337287644, 106023.4698826501, 108825.45587272171, 110956.0, 112719.43640281746, 112859.43570282261, 65898.34101659017, 92778.53610731903, 99069.12101426769, 100191.99616007639, 101522.0, 46525.534744652796, 81880.59059704782, 82771.0, 85554.0, 85977.0, 9295.07049295065, 9633.373830700983, 9715.319927605, 9730.90785481125, 9754.492987649788, 46287.768561156976, 46662.76668616635, 53010.939781203386, 53158.73420632872, 55618.72190639021, 55447.44552554423, 69073.65463172652, 73124.63437682777, 74000.0, 77383.61308193424, 11850.76298474043, 12310.322932238812, 12608.74782504363, 12657.746845063231, 12817.359132043173, 10405.791884162425, 12191.817122742988, 12535.749285014066, 12962.611121666365, 13407.396667149807, 10705.785884282426, 11873.940630297138, 13078.542251021168, 13405.396757145756, 13457.663558411117, 12834.67913302175, 13064.34678266102, 13148.737025259632, 13418.865811341762, 13624.386902589606, 12765.170263932814, 12773.55292564793, 12775.61673149807, 12879.742405152032, 12970.286634235208, 10197.370062629261, 10312.535935882754, 10395.792084158425, 10448.738781530523, 10996.560137594726, 9007.63969441215, 10721.195910306602, 10879.728006799893, 10947.45262736844, 10982.945085274523], "value": 7139.563196726138, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1566.6999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 118.0, 118.0, 118.0, 118.0, 119.0, 118.0, 119.0, 120.0], "value": 117.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 117.0, 118.0, 152.0, 116.0, 117.0, 115.0, 118.0, 170.0, 154.0], "value": 132.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 210.0, 234.0, 271.0, 201.0, 197.0, 198.0, 223.0, 254.0, 193.0], "value": 218.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 59.0, 57.0, 85.0, 62.0, 59.0, 60.0, 58.0, 70.0, 70.0], "value": 66.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 69.0, 84.0, 70.0, 68.0, 71.0, 82.0, 68.0, 71.0, 81.0], "value": 74.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 88.0, 93.0, 87.0, 85.0, 129.0, 125.0, 88.0, 94.0, 114.0], "value": 99.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 96.0, 96.0, 97.0, 96.0, 96.0, 106.0, 126.0, 96.0, 97.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 116.0, 120.0, 92.0, 112.0, 105.0, 91.0, 93.0, 86.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 57.0, 58.0, 59.0, 58.0, 58.0, 57.0, 57.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 76.0, 76.0, 74.0, 95.0, 91.0, 102.0, 105.0, 102.0, 70.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 80.0, 117.0, 113.0, 109.0, 89.0, 89.0, 88.0, 88.0, 88.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 194.0, 164.0, 155.0, 150.0, 150.0, 153.0, 159.0, 193.0, 163.0], "value": 163.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 174.0, 177.0, 175.0, 176.0, 177.0, 210.0, 203.0, 177.0], "value": 182.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 71.0, 79.0, 78.0, 85.0, 85.0, 71.0, 66.0, 81.0, 64.0], "value": 74.7, "unit": "score"}]}]} 20:59:28 INFO - Return code: 0 20:59:29 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:59:29 ERROR - # TBPL SUCCESS # 20:59:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:59:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:59:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:59:29 INFO - 2016-11-27 20:59:29.036 screenresolution[1683:13774] starting screenresolution argv=screenresolution get 20:59:29 INFO - 2016-11-27 20:59:29.038 screenresolution[1683:13774] Display 0: 1600x1200x32@60 20:59:29 INFO - 2016-11-27 20:59:29.045 screenresolution[1684:13776] starting screenresolution argv=screenresolution list 20:59:29 INFO - Available Modes on Display 0 20:59:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:59:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:59:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:59:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:59:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:59:29 INFO - Intel Iris: 20:59:29 INFO - Chipset Model: Intel Iris 20:59:29 INFO - Type: GPU 20:59:29 INFO - Bus: Built-In 20:59:29 INFO - VRAM (Dynamic, Max): 1536 MB 20:59:29 INFO - Vendor: Intel (0x8086) 20:59:29 INFO - Device ID: 0x0a2e 20:59:29 INFO - Revision ID: 0x0009 20:59:29 INFO - Displays: 20:59:29 INFO - Display: 20:59:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:59:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:59:29 INFO - Display Serial Number: 1600x1200 60 20:59:29 INFO - Main Display: Yes 20:59:29 INFO - Mirror: Off 20:59:29 INFO - Online: Yes 20:59:29 INFO - Rotation: Supported 20:59:29 INFO - Return code: 0 20:59:29 INFO - Running post-action listener: _resource_record_post_action 20:59:29 INFO - [mozharness: 2016-11-28 04:59:29.289766Z] Finished run-tests step (success) 20:59:29 INFO - Running post-run listener: _resource_record_post_run 20:59:29 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 33063424; Write bytes: 698930688; Read time: 198; Write time: 2029 20:59:29 INFO - TinderboxPrint: CPU usage
54.7% 20:59:29 INFO - TinderboxPrint: I/O read bytes / time
33,063,424 / 198 20:59:29 INFO - TinderboxPrint: I/O write bytes / time
698,930,688 / 2,029 20:59:29 INFO - TinderboxPrint: CPU idle
2,196.7 (45.3%) 20:59:29 INFO - TinderboxPrint: CPU system
988.6 (20.4%) 20:59:29 INFO - TinderboxPrint: CPU user
1,664.5 (34.3%) 20:59:29 INFO - TinderboxPrint: Swap in / out
412,508,160 / 0 20:59:29 INFO - install - Wall time: 33s; CPU: 54.0%; Read bytes: 374317568; Write bytes: 367736832; Read time: 29109; Write time: 789 20:59:29 INFO - run-tests - Wall time: 1179s; CPU: 55.0%; Read bytes: 31109632; Write bytes: 312782336; Read time: 182; Write time: 1187 20:59:29 INFO - Running post-run listener: _upload_blobber_files 20:59:29 INFO - Blob upload gear active. 20:59:29 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:59:29 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 20:59:29 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', 'Graphics', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:59:29 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 Graphics -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:59:30 INFO - (blobuploader) - INFO - Open directory for files ... 20:59:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:59:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:59:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:59:31 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:59:31 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:59:31 INFO - (blobuploader) - INFO - Done attempting. 20:59:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:59:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:59:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:59:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:59:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:59:32 INFO - (blobuploader) - INFO - Done attempting. 20:59:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:59:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:59:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:59:34 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:59:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:59:34 INFO - (blobuploader) - INFO - Done attempting. 20:59:34 INFO - (blobuploader) - INFO - Iteration through files over. 20:59:34 INFO - Return code: 0 20:59:34 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:59:34 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:59:34 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c4571b7197a4370ffbb322b4a02b3dd960f0fbe4477632c6ccfd813072db8a974739081a000fb02eaae66d6e171a1e48c7430f6d2b695a34d1a5eef5558a970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b96c78cd3e48fe30b23366ef572961996278023e9aab9f388405412365774b35b2645ce5af98644096ccc4b1fb155a4ca7a643b02e6996538fc6f3d24d73c337", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4acb4829a785aa06a3ea8bb071259d723dfcf7f2bf9427430906ab70ba550c67503d2e9259194d40d9b08a2da4d45e7faf190990c71527a605f476b05d9bdf39"} 20:59:34 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:59:34 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:59:34 INFO - Contents: 20:59:34 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c4571b7197a4370ffbb322b4a02b3dd960f0fbe4477632c6ccfd813072db8a974739081a000fb02eaae66d6e171a1e48c7430f6d2b695a34d1a5eef5558a970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b96c78cd3e48fe30b23366ef572961996278023e9aab9f388405412365774b35b2645ce5af98644096ccc4b1fb155a4ca7a643b02e6996538fc6f3d24d73c337", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4acb4829a785aa06a3ea8bb071259d723dfcf7f2bf9427430906ab70ba550c67503d2e9259194d40d9b08a2da4d45e7faf190990c71527a605f476b05d9bdf39"} 20:59:34 INFO - Running post-run listener: copy_logs_to_upload_dir 20:59:34 INFO - Copying logs to upload dir... 20:59:34 INFO - mkdir: /builds/slave/test/build/upload/logs 20:59:34 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.133971 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-11-27 20:59:34.458780) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 20:59:34.462188) ========= 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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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/Graphics/sha512/9c4571b7197a4370ffbb322b4a02b3dd960f0fbe4477632c6ccfd813072db8a974739081a000fb02eaae66d6e171a1e48c7430f6d2b695a34d1a5eef5558a970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b96c78cd3e48fe30b23366ef572961996278023e9aab9f388405412365774b35b2645ce5af98644096ccc4b1fb155a4ca7a643b02e6996538fc6f3d24d73c337", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4acb4829a785aa06a3ea8bb071259d723dfcf7f2bf9427430906ab70ba550c67503d2e9259194d40d9b08a2da4d45e7faf190990c71527a605f476b05d9bdf39"} build_url:https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011082 build_url: 'https://queue.taskcluster.net/v1/task/dg4X8i5tRHOjimzp8DrUdA/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/9c4571b7197a4370ffbb322b4a02b3dd960f0fbe4477632c6ccfd813072db8a974739081a000fb02eaae66d6e171a1e48c7430f6d2b695a34d1a5eef5558a970", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/b96c78cd3e48fe30b23366ef572961996278023e9aab9f388405412365774b35b2645ce5af98644096ccc4b1fb155a4ca7a643b02e6996538fc6f3d24d73c337", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Graphics/sha512/4acb4829a785aa06a3ea8bb071259d723dfcf7f2bf9427430906ab70ba550c67503d2e9259194d40d9b08a2da4d45e7faf190990c71527a605f476b05d9bdf39"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-27 20:59:34.496963) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 20:59:34.497287) ========= 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.bMbHkRiRbD/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.3ORGtiNDPS/Listeners TMPDIR=/var/folders/q6/xpx_j8b569553pg_p0r3yk4c00000w/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.005028 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-27 20:59:34.547425) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 20:59:34.547960) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-27 20:59:34.548511) ========= ========= Total master_lag: 0.43 =========