builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0073 starttime: 1468087236.48 results: success (0) buildid: 20160709090026 builduid: b44a112bf0af4a51b6b07ce85643989b revision: 679118259e91f40d4a8f968f03ec4cff066cdb5b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:36.480656) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:36.481588) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:36.481904) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:36.731762) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:36.732072) ========= 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.210XvjmTW2/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.j7XcVKQD7a/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-07-09 11:00:36-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2016-07-09 11:00:37 (161 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.496499 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:37.270888) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:37.271186) ========= 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.210XvjmTW2/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.j7XcVKQD7a/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.020103 ========= master_lag: 0.09 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:37.378276) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:37.378601) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 679118259e91f40d4a8f968f03ec4cff066cdb5b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 679118259e91f40d4a8f968f03ec4cff066cdb5b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.210XvjmTW2/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.j7XcVKQD7a/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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-07-09 11:00:37,463 truncating revision to first 12 chars 2016-07-09 11:00:37,463 Setting DEBUG logging. 2016-07-09 11:00:37,463 attempt 1/10 2016-07-09 11:00:37,463 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/679118259e91?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-07-09 11:00:38,140 unpacking tar archive at: autoland-679118259e91/testing/mozharness/ program finished with exit code 0 elapsedTime=0.927585 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:38.341874) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:38.342196) ========= script_repo_revision: 679118259e91f40d4a8f968f03ec4cff066cdb5b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:38.343104) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:38.343397) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-07-09 11:00:38.362407) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 3 secs) (at 2016-07-09 11:00:38.362861) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.210XvjmTW2/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.j7XcVKQD7a/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 11:00:38 INFO - MultiFileLogger online at 20160709 11:00:38 in /builds/slave/test 11:00:38 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 11:00:38 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:00:38 INFO - {'append_to_log': False, 11:00:38 INFO - 'base_work_dir': '/builds/slave/test', 11:00:38 INFO - 'blob_upload_branch': 'Autoland', 11:00:38 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:00:38 INFO - 'branch': 'Autoland', 11:00:38 INFO - 'buildbot_json_path': 'buildprops.json', 11:00:38 INFO - 'config_files': ('talos/mac_config.py',), 11:00:38 INFO - 'default_actions': ('clobber', 11:00:38 INFO - 'read-buildbot-config', 11:00:38 INFO - 'download-and-extract', 11:00:38 INFO - 'populate-webroot', 11:00:38 INFO - 'create-virtualenv', 11:00:38 INFO - 'install', 11:00:38 INFO - 'run-tests'), 11:00:38 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:00:38 INFO - 'download_minidump_stackwalk': True, 11:00:38 INFO - 'download_symbols': 'ondemand', 11:00:38 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:00:38 INFO - 'tooltool.py': '/tools/tooltool.py', 11:00:38 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:00:38 INFO - '/tools/misc-python/virtualenv.py')}, 11:00:38 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:00:38 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:00:38 INFO - 'installer_path': 'installer.exe', 11:00:38 INFO - 'log_level': 'info', 11:00:38 INFO - 'log_name': 'talos', 11:00:38 INFO - 'log_to_console': True, 11:00:38 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:00:38 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:00:38 INFO - 'opt_config_files': (), 11:00:38 INFO - 'pip_index': False, 11:00:38 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:00:38 INFO - 'cmd': ('bash', 11:00:38 INFO - '-c', 11:00:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:00:38 INFO - 'enabled': True, 11:00:38 INFO - 'halt_on_failure': False, 11:00:38 INFO - 'name': 'check_screen_resolution'},), 11:00:38 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:00:38 INFO - 'cmd': ('bash', 11:00:38 INFO - '-c', 11:00:38 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:00:38 INFO - 'enabled': True, 11:00:38 INFO - 'halt_on_failure': False, 11:00:38 INFO - 'name': 'check_screen_resolution'},), 11:00:38 INFO - 'run_cmd_checks_enabled': True, 11:00:38 INFO - 'sps_profile': False, 11:00:38 INFO - 'sps_profile_interval': 0, 11:00:38 INFO - 'suite': 'dromaeojs-e10s', 11:00:38 INFO - 'system_bits': '32', 11:00:38 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:00:38 INFO - 'title': 't-yosemite-r7-0073', 11:00:38 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:00:38 INFO - 'use_talos_json': True, 11:00:38 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:00:38 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:00:38 INFO - 'work_dir': 'build'} 11:00:38 INFO - ##### 11:00:38 INFO - ##### Running clobber step. 11:00:38 INFO - ##### 11:00:38 INFO - Running pre-action listener: _resource_record_pre_action 11:00:38 INFO - Running main action method: clobber 11:00:38 INFO - rmtree: /builds/slave/test/build 11:00:38 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:00:39 INFO - Running post-action listener: _resource_record_post_action 11:00:39 INFO - ##### 11:00:39 INFO - ##### Finished clobber step (success) 11:00:39 INFO - ##### 11:00:39 INFO - ##### 11:00:39 INFO - ##### Running read-buildbot-config step. 11:00:39 INFO - ##### 11:00:39 INFO - Running pre-action listener: _resource_record_pre_action 11:00:39 INFO - Running main action method: read_buildbot_config 11:00:39 INFO - Using buildbot properties: 11:00:39 INFO - { 11:00:39 INFO - "project": "", 11:00:39 INFO - "product": "firefox", 11:00:39 INFO - "script_repo_revision": "production", 11:00:39 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 11:00:39 INFO - "repository": "", 11:00:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 11:00:39 INFO - "buildid": "20160709090026", 11:00:39 INFO - "pgo_build": "False", 11:00:39 INFO - "basedir": "/builds/slave/test", 11:00:39 INFO - "buildnumber": 39, 11:00:39 INFO - "slavename": "t-yosemite-r7-0073", 11:00:39 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:00:39 INFO - "platform": "yosemite_r7", 11:00:39 INFO - "scheduler": "tests-autoland-macosx64-talos", 11:00:39 INFO - "branch": "autoland", 11:00:39 INFO - "repo_path": "integration/autoland", 11:00:39 INFO - "revision": "679118259e91f40d4a8f968f03ec4cff066cdb5b", 11:00:39 INFO - "stage_platform": "macosx64", 11:00:39 INFO - "builduid": "b44a112bf0af4a51b6b07ce85643989b", 11:00:39 INFO - "slavebuilddir": "test" 11:00:39 INFO - } 11:00:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg. 11:00:39 INFO - Running post-action listener: _resource_record_post_action 11:00:39 INFO - ##### 11:00:39 INFO - ##### Finished read-buildbot-config step (success) 11:00:39 INFO - ##### 11:00:39 INFO - ##### 11:00:39 INFO - ##### Running download-and-extract step. 11:00:39 INFO - ##### 11:00:39 INFO - Running pre-action listener: _resource_record_pre_action 11:00:39 INFO - Running main action method: download_and_extract 11:00:39 INFO - mkdir: /builds/slave/test/build/tests 11:00:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:00:39 INFO - https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json matches https://queue.taskcluster.net 11:00:39 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:00:39 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json 11:00:39 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:00:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #1 11:01:09 WARNING - Timed out accessing http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json: timed out 11:01:09 INFO - retry: attempt #1 caught exception: timed out 11:01:09 INFO - retry: Failed, sleeping 30 seconds before retrying 11:01:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json'}, attempt #2 11:01:40 INFO - Downloaded 1199 bytes. 11:01:40 INFO - Reading from file /builds/slave/test/build/firefox-50.0a1.en-US.mac.test_packages.json 11:01:40 INFO - Using the following test package requirements: 11:01:40 INFO - {u'common': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:01:40 INFO - u'cppunittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.cppunittest.tests.zip'], 11:01:40 INFO - u'gtest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.gtest.tests.zip'], 11:01:40 INFO - u'jittest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'jsshell-mac.zip'], 11:01:40 INFO - u'mochitest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.mochitest.tests.zip'], 11:01:40 INFO - u'mozbase': [u'firefox-50.0a1.en-US.mac.common.tests.zip'], 11:01:40 INFO - u'reftest': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.reftest.tests.zip'], 11:01:40 INFO - u'talos': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.talos.tests.zip'], 11:01:40 INFO - u'web-platform': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.web-platform.tests.zip'], 11:01:40 INFO - u'xpcshell': [u'firefox-50.0a1.en-US.mac.common.tests.zip', 11:01:40 INFO - u'firefox-50.0a1.en-US.mac.xpcshell.tests.zip']} 11:01:40 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:01: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:40 INFO - https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:01:40 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:01:40 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:01:40 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:01:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:01:42 INFO - Downloaded 18806880 bytes. 11:01:42 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:01:42 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:01:45 INFO - Return code: 0 11:01:45 INFO - Downloading packages: [u'firefox-50.0a1.en-US.mac.common.tests.zip', u'firefox-50.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:01:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:45 INFO - https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip matches https://queue.taskcluster.net 11:01:45 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:01:45 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:01:45 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip 11:01:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:01:47 INFO - Downloaded 18806880 bytes. 11:01:47 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:01:47 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 11:01:50 INFO - Return code: 0 11:01: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:50 INFO - https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip matches https://queue.taskcluster.net 11:01:50 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:01:50 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:01:50 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip 11:01:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:01:51 INFO - Downloaded 14403333 bytes. 11:01:51 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 11:01:51 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-50.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 11:01:52 INFO - Return code: 0 11:01:52 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:01:52 INFO - https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg matches https://queue.taskcluster.net 11:01:52 INFO - URL Candidate: http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:01:52 INFO - trying http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:01:52 INFO - Downloading http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg 11:01:52 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'http://queue.taskcluster.net.proxxy1.srv.releng.scl3.mozilla.com/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg'}, attempt #1 11:01:55 INFO - Downloaded 112387296 bytes. 11:01:55 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:01:55 INFO - mkdir: /builds/slave/test/properties 11:01:55 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:01:55 INFO - Writing to file /builds/slave/test/properties/build_url 11:01:55 INFO - Contents: 11:01:55 INFO - build_url:https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg 11:01:56 INFO - Running post-action listener: _resource_record_post_action 11:01:56 INFO - Running post-action listener: set_extra_try_arguments 11:01:56 INFO - ##### 11:01:56 INFO - ##### Finished download-and-extract step (success) 11:01:56 INFO - ##### 11:01:56 INFO - ##### 11:01:56 INFO - ##### Running populate-webroot step. 11:01:56 INFO - ##### 11:01:56 INFO - Running pre-action listener: _resource_record_pre_action 11:01:56 INFO - Running main action method: populate_webroot 11:01:56 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:01:56 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:01:56 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:01:56 INFO - '--tppagecycles', 11:01:56 INFO - '7'], 11:01:56 INFO - 'tests': ['tsvgm']}}, 11:01:56 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:01:56 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:01:56 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:01:56 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:01:56 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:01:56 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:01:56 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['damp', 'tps']}, 11:01:56 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'tests': ['damp', 'tps']}, 11:01:56 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['dromaeo_dom']}, 11:01:56 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:01:56 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['basic_compositor_video']}, 11:01:56 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:01:56 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['a11yr', 11:01:56 INFO - 'ts_paint', 11:01:56 INFO - 'tpaint', 11:01:56 INFO - 'sessionrestore', 11:01:56 INFO - 'sessionrestore_no_auto_restore', 11:01:56 INFO - 'tabpaint']}, 11:01:56 INFO - 'other-e10s': {'tests': ['a11yr', 11:01:56 INFO - 'ts_paint', 11:01:56 INFO - 'tpaint', 11:01:56 INFO - 'sessionrestore', 11:01:56 INFO - 'sessionrestore_no_auto_restore', 11:01:56 INFO - 'tabpaint']}, 11:01:56 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:01:56 INFO - 'ts_paint', 11:01:56 INFO - 'tpaint', 11:01:56 INFO - 'sessionrestore', 11:01:56 INFO - 'sessionrestore_no_auto_restore', 11:01:56 INFO - 'tabpaint']}, 11:01:56 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:01:56 INFO - 'ts_paint', 11:01:56 INFO - 'tpaint', 11:01:56 INFO - 'sessionrestore', 11:01:56 INFO - 'sessionrestore_no_auto_restore', 11:01:56 INFO - 'tabpaint']}, 11:01:56 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['a11yr', 11:01:56 INFO - 'ts_paint', 11:01:56 INFO - 'tpaint', 11:01:56 INFO - 'sessionrestore', 11:01:56 INFO - 'sessionrestore_no_auto_restore', 11:01:56 INFO - 'tabpaint']}, 11:01:56 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['a11yr', 11:01:56 INFO - 'ts_paint', 11:01:56 INFO - 'tpaint', 11:01:56 INFO - 'sessionrestore', 11:01:56 INFO - 'sessionrestore_no_auto_restore', 11:01:56 INFO - 'tabpaint']}, 11:01:56 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['tsvgx', 11:01:56 INFO - 'tsvgr_opacity', 11:01:56 INFO - 'tart', 11:01:56 INFO - 'tscrollx', 11:01:56 INFO - 'cart']}, 11:01:56 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:01:56 INFO - 'tsvgr_opacity', 11:01:56 INFO - 'tart', 11:01:56 INFO - 'tscrollx', 11:01:56 INFO - 'cart']}, 11:01:56 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'talos_options': ['--disable-e10s'], 11:01:56 INFO - 'tests': ['tp5o']}, 11:01:56 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'tests': ['tp5o']}, 11:01:56 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'talos_options': ['--disable-e10s', 11:01:56 INFO - '--xperf_path', 11:01:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:01:56 INFO - 'tests': ['tp5n']}, 11:01:56 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:01:56 INFO - 'talos_options': ['--xperf_path', 11:01:56 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:01:56 INFO - 'tests': ['tp5n']}}, 11:01:56 INFO - 'talos.zip': {'path': '', 11:01:56 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:01:56 INFO - Running post-action listener: _resource_record_post_action 11:01:56 INFO - ##### 11:01:56 INFO - ##### Finished populate-webroot step (success) 11:01:56 INFO - ##### 11:01:56 INFO - ##### 11:01:56 INFO - ##### Running create-virtualenv step. 11:01:56 INFO - ##### 11:01:56 INFO - Running pre-action listener: _resource_record_pre_action 11:01:56 INFO - Running main action method: create_virtualenv 11:01:56 INFO - Creating virtualenv /builds/slave/test/build/venv 11:01:56 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 11:01:56 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:01:56 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:01:56 INFO - Using real prefix '/tools/python27' 11:01:56 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:01:57 INFO - Installing distribute.............................................................................................................................................................................................done. 11:02:00 INFO - Installing pip.................done. 11:02:00 INFO - Return code: 0 11:02:00 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02: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', 'pip>=1.5'] in /builds/slave/test/build 11:02: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 pip>=1.5 11:02:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:00 INFO - 'HOME': '/Users/cltbld', 11:02:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:00 INFO - 'LOGNAME': 'cltbld', 11:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:00 INFO - 'MOZ_NO_REMOTE': '1', 11:02:00 INFO - 'NO_EM_RESTART': '1', 11:02:00 INFO - 'PAGER': '/bin/cat', 11:02:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:00 INFO - 'PWD': '/builds/slave/test', 11:02:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:00 INFO - 'SHELL': '/bin/bash', 11:02:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:00 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:00 INFO - 'USER': 'cltbld', 11:02:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:00 INFO - 'XPC_FLAGS': '0x0', 11:02:00 INFO - 'XPC_SERVICE_NAME': '0', 11:02:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:00 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 11:02:00 INFO - Cleaning up... 11:02:00 INFO - Return code: 0 11:02:00 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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', '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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 11:02: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 psutil>=3.1.1 11:02:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:00 INFO - 'HOME': '/Users/cltbld', 11:02:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:00 INFO - 'LOGNAME': 'cltbld', 11:02:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:00 INFO - 'MOZ_NO_REMOTE': '1', 11:02:00 INFO - 'NO_EM_RESTART': '1', 11:02:00 INFO - 'PAGER': '/bin/cat', 11:02:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:00 INFO - 'PWD': '/builds/slave/test', 11:02:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:00 INFO - 'SHELL': '/bin/bash', 11:02:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:00 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:00 INFO - 'USER': 'cltbld', 11:02:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:00 INFO - 'XPC_FLAGS': '0x0', 11:02:00 INFO - 'XPC_SERVICE_NAME': '0', 11:02:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:00 INFO - Downloading/unpacking psutil>=3.1.1 11:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:02:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:02:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:02:04 INFO - Installing collected packages: psutil 11:02:04 INFO - Running setup.py install for psutil 11:02:04 INFO - building 'psutil._psutil_osx' extension 11:02:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:02:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:02:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:02:04 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:02:04 INFO - building 'psutil._psutil_posix' extension 11:02:04 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:02:05 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:02:05 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:02:05 INFO - ^ 11:02:05 INFO - 1 warning generated. 11:02:05 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:02:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:02:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:02:05 INFO - Successfully installed psutil 11:02:05 INFO - Cleaning up... 11:02:05 INFO - Return code: 0 11:02:05 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:02:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:02:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:05 INFO - 'HOME': '/Users/cltbld', 11:02:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:05 INFO - 'LOGNAME': 'cltbld', 11:02:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:05 INFO - 'MOZ_NO_REMOTE': '1', 11:02:05 INFO - 'NO_EM_RESTART': '1', 11:02:05 INFO - 'PAGER': '/bin/cat', 11:02:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:05 INFO - 'PWD': '/builds/slave/test', 11:02:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:05 INFO - 'SHELL': '/bin/bash', 11:02:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:05 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:05 INFO - 'USER': 'cltbld', 11:02:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:05 INFO - 'XPC_FLAGS': '0x0', 11:02:05 INFO - 'XPC_SERVICE_NAME': '0', 11:02:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:05 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:07 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:02:07 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:02:08 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:02:08 INFO - Installing collected packages: mozsystemmonitor 11:02:08 INFO - Running setup.py install for mozsystemmonitor 11:02:08 INFO - Successfully installed mozsystemmonitor 11:02:08 INFO - Cleaning up... 11:02:08 INFO - Return code: 0 11:02:08 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:02:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:02:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:08 INFO - 'HOME': '/Users/cltbld', 11:02:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:08 INFO - 'LOGNAME': 'cltbld', 11:02:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:08 INFO - 'MOZ_NO_REMOTE': '1', 11:02:08 INFO - 'NO_EM_RESTART': '1', 11:02:08 INFO - 'PAGER': '/bin/cat', 11:02:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:08 INFO - 'PWD': '/builds/slave/test', 11:02:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:08 INFO - 'SHELL': '/bin/bash', 11:02:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:08 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:08 INFO - 'USER': 'cltbld', 11:02:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:08 INFO - 'XPC_FLAGS': '0x0', 11:02:08 INFO - 'XPC_SERVICE_NAME': '0', 11:02:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:08 INFO - Downloading/unpacking jsonschema==2.5.1 11:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:08 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:08 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:08 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:08 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:10 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:02:10 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:11 INFO - Downloading functools32-3.2.3-2.tar.gz 11:02:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:02:11 INFO - warning: no files found matching '*.txt' 11:02:11 INFO - no previously-included directories found matching 'build' 11:02:11 INFO - no previously-included directories found matching 'dist' 11:02:11 INFO - no previously-included directories found matching '.git*' 11:02:11 INFO - Installing collected packages: jsonschema, functools32 11:02:11 INFO - Running setup.py install for functools32 11:02:11 INFO - warning: no files found matching '*.txt' 11:02:11 INFO - no previously-included directories found matching 'build' 11:02:11 INFO - no previously-included directories found matching 'dist' 11:02:11 INFO - no previously-included directories found matching '.git*' 11:02:11 INFO - Successfully installed jsonschema functools32 11:02:11 INFO - Cleaning up... 11:02:11 INFO - Return code: 0 11:02:11 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:02:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:11 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:11 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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:02:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:02:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:11 INFO - 'HOME': '/Users/cltbld', 11:02:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:11 INFO - 'LOGNAME': 'cltbld', 11:02:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:11 INFO - 'MOZ_NO_REMOTE': '1', 11:02:11 INFO - 'NO_EM_RESTART': '1', 11:02:11 INFO - 'PAGER': '/bin/cat', 11:02:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:11 INFO - 'PWD': '/builds/slave/test', 11:02:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:11 INFO - 'SHELL': '/bin/bash', 11:02:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:11 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:11 INFO - 'USER': 'cltbld', 11:02:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:11 INFO - 'XPC_FLAGS': '0x0', 11:02:11 INFO - 'XPC_SERVICE_NAME': '0', 11:02:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:12 INFO - Downloading/unpacking blobuploader==1.2.4 11:02: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 11:02: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 11:02: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 11:02: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 11:02: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 11:02: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 11:02:14 INFO - Downloading blobuploader-1.2.4.tar.gz 11:02:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:02:14 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:02:15 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:15 INFO - Downloading docopt-0.6.1.tar.gz 11:02:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:02:15 INFO - Installing collected packages: blobuploader, requests, docopt 11:02:15 INFO - Running setup.py install for blobuploader 11:02:15 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:02:15 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:02:15 INFO - Running setup.py install for requests 11:02:16 INFO - Running setup.py install for docopt 11:02:16 INFO - Successfully installed blobuploader requests docopt 11:02:16 INFO - Cleaning up... 11:02:16 INFO - Return code: 0 11:02:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02: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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:16 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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:02:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:02:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:16 INFO - 'HOME': '/Users/cltbld', 11:02:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:16 INFO - 'LOGNAME': 'cltbld', 11:02:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:16 INFO - 'MOZ_NO_REMOTE': '1', 11:02:16 INFO - 'NO_EM_RESTART': '1', 11:02:16 INFO - 'PAGER': '/bin/cat', 11:02:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:16 INFO - 'PWD': '/builds/slave/test', 11:02:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:16 INFO - 'SHELL': '/bin/bash', 11:02:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:16 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:16 INFO - 'USER': 'cltbld', 11:02:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:16 INFO - 'XPC_FLAGS': '0x0', 11:02:16 INFO - 'XPC_SERVICE_NAME': '0', 11:02:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:02:16 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-WOlBNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-t8L7x7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-WFbCRt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1TnMfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-2GXsbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-AwZy7U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-05XxFm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-I1k0c6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-HSxZ65-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-6anh6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:02:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:02:17 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-joxr8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:02:18 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-0uycO2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:02:18 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Tj4aJF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:02:18 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-JUPK1j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:18 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-1rb8Lw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:02:18 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-F4UUtD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:02:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:02:18 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-573Zw2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:02:18 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:02:18 INFO - Running setup.py install for manifestparser 11:02:18 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:02:18 INFO - Running setup.py install for mozcrash 11:02:18 INFO - Running setup.py install for mozdebug 11:02:19 INFO - Running setup.py install for mozdevice 11:02:19 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Running setup.py install for mozfile 11:02:19 INFO - Running setup.py install for mozhttpd 11:02:19 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Running setup.py install for mozinfo 11:02:19 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Running setup.py install for mozInstall 11:02:19 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:02:19 INFO - Running setup.py install for mozleak 11:02:20 INFO - Running setup.py install for mozlog 11:02:20 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:02:20 INFO - Running setup.py install for moznetwork 11:02:20 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:02:20 INFO - Running setup.py install for mozprocess 11:02:20 INFO - Running setup.py install for mozprofile 11:02:20 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:02:20 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:02:20 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:02:20 INFO - Running setup.py install for mozrunner 11:02:20 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:02:20 INFO - Running setup.py install for mozscreenshot 11:02:20 INFO - Running setup.py install for moztest 11:02:21 INFO - Running setup.py install for mozversion 11:02:21 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:02:21 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:02:21 INFO - Cleaning up... 11:02:21 INFO - Return code: 0 11:02:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:21 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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:02:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:02:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:21 INFO - 'HOME': '/Users/cltbld', 11:02:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:21 INFO - 'LOGNAME': 'cltbld', 11:02:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:21 INFO - 'MOZ_NO_REMOTE': '1', 11:02:21 INFO - 'NO_EM_RESTART': '1', 11:02:21 INFO - 'PAGER': '/bin/cat', 11:02:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:21 INFO - 'PWD': '/builds/slave/test', 11:02:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:21 INFO - 'SHELL': '/bin/bash', 11:02:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:21 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:21 INFO - 'USER': 'cltbld', 11:02:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:21 INFO - 'XPC_FLAGS': '0x0', 11:02:21 INFO - 'XPC_SERVICE_NAME': '0', 11:02:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:02:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-wIF1wB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:02:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-IlMl4J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:02:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-PaNzDH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:02:21 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-Sh4Nme-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:02:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:02:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-5QPj53-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-TGvDFH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-eRrkdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-N95SeE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-cCXZ2N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-0ymSZR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.2 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-8z6UCT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-cSkGoj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-zxJMoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:02:22 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 11:02:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:02:22 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-0DKkwH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 11:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:23 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-pBSilp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 11:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:02:23 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-HYuoYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 11:02:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:02:23 INFO - Running setup.py (path:/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/pip-tKe4Gl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:02:23 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:02:23 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.2->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:02:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:02:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:02:25 INFO - Downloading blessings-1.6.tar.gz 11:02:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:02:25 INFO - Installing collected packages: blessings 11:02:25 INFO - Running setup.py install for blessings 11:02:26 INFO - Successfully installed blessings 11:02:26 INFO - Cleaning up... 11:02:26 INFO - Return code: 0 11:02:26 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:02:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:26 INFO - Reading from file tmpfile_stdout 11:02:26 INFO - Current package versions: 11:02:26 INFO - blessings == 1.6 11:02:26 INFO - blobuploader == 1.2.4 11:02:26 INFO - docopt == 0.6.1 11:02:26 INFO - functools32 == 3.2.3-2 11:02:26 INFO - jsonschema == 2.5.1 11:02:26 INFO - manifestparser == 1.1 11:02:26 INFO - mozInstall == 1.12 11:02:26 INFO - mozcrash == 0.17 11:02:26 INFO - mozdebug == 0.1 11:02:26 INFO - mozdevice == 0.48 11:02:26 INFO - mozfile == 1.2 11:02:26 INFO - mozhttpd == 0.7 11:02:26 INFO - mozinfo == 0.9 11:02:26 INFO - mozleak == 0.1 11:02:26 INFO - mozlog == 3.2 11:02:26 INFO - moznetwork == 0.27 11:02:26 INFO - mozprocess == 0.23 11:02:26 INFO - mozprofile == 0.28 11:02:26 INFO - mozrunner == 6.12 11:02:26 INFO - mozscreenshot == 0.1 11:02:26 INFO - mozsystemmonitor == 0.3 11:02:26 INFO - moztest == 0.7 11:02:26 INFO - mozversion == 1.4 11:02:26 INFO - psutil == 3.1.1 11:02:26 INFO - requests == 1.2.3 11:02:26 INFO - wsgiref == 0.1.2 11:02:26 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:26 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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:02:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:26 INFO - 'HOME': '/Users/cltbld', 11:02:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:26 INFO - 'LOGNAME': 'cltbld', 11:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:26 INFO - 'MOZ_NO_REMOTE': '1', 11:02:26 INFO - 'NO_EM_RESTART': '1', 11:02:26 INFO - 'PAGER': '/bin/cat', 11:02:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:26 INFO - 'PWD': '/builds/slave/test', 11:02:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:26 INFO - 'SHELL': '/bin/bash', 11:02:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:26 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:26 INFO - 'USER': 'cltbld', 11:02:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:26 INFO - 'XPC_FLAGS': '0x0', 11:02:26 INFO - 'XPC_SERVICE_NAME': '0', 11:02:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 11:02:26 INFO - Cleaning up... 11:02:26 INFO - Return code: 0 11:02:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:02:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:02:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:02:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:02:26 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 0x100ecfa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f64618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd0c9d6d2f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, '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.210XvjmTW2/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.j7XcVKQD7a/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 11:02:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 11:02:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 11:02:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:02:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:02:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:02:26 INFO - 'HOME': '/Users/cltbld', 11:02:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:02:26 INFO - 'LOGNAME': 'cltbld', 11:02:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:02:26 INFO - 'MOZ_NO_REMOTE': '1', 11:02:26 INFO - 'NO_EM_RESTART': '1', 11:02:26 INFO - 'PAGER': '/bin/cat', 11:02:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:02:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:02:26 INFO - 'PWD': '/builds/slave/test', 11:02:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:02:26 INFO - 'SHELL': '/bin/bash', 11:02:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:02:26 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:02:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:02:26 INFO - 'USER': 'cltbld', 11:02:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:02:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:02:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:02:26 INFO - 'XPC_FLAGS': '0x0', 11:02:26 INFO - 'XPC_SERVICE_NAME': '0', 11:02:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:02:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:02:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:02:26 INFO - Cleaning up... 11:02:26 INFO - Return code: 0 11:02:26 INFO - Running post-action listener: _resource_record_post_action 11:02:26 INFO - Running post-action listener: _start_resource_monitoring 11:02:27 INFO - Starting resource monitoring. 11:02:27 INFO - ##### 11:02:27 INFO - ##### Finished create-virtualenv step (success) 11:02:27 INFO - ##### 11:02:27 INFO - ##### 11:02:27 INFO - ##### Running install step. 11:02:27 INFO - ##### 11:02:27 INFO - Running pre-action listener: _resource_record_pre_action 11:02:27 INFO - Running main action method: install 11:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:02:27 INFO - Reading from file tmpfile_stdout 11:02:27 INFO - Detecting whether we're running mozinstall >=1.0... 11:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:02:27 INFO - Reading from file tmpfile_stdout 11:02:27 INFO - Output received: 11:02:27 INFO - Usage: mozinstall [options] installer 11:02:27 INFO - Options: 11:02:27 INFO - -h, --help show this help message and exit 11:02:27 INFO - -d DEST, --destination=DEST 11:02:27 INFO - Directory to install application into. [default: 11:02:27 INFO - "/builds/slave/test"] 11:02:27 INFO - --app=APP Application being installed. [default: firefox] 11:02:27 INFO - mkdir: /builds/slave/test/build/application 11:02:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:02:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-50.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:03:02 INFO - Reading from file tmpfile_stdout 11:03:02 INFO - Output received: 11:03:02 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:03:02 INFO - Running post-action listener: _resource_record_post_action 11:03:02 INFO - ##### 11:03:02 INFO - ##### Finished install step (success) 11:03:02 INFO - ##### 11:03:02 INFO - ##### 11:03:02 INFO - ##### Running run-tests step. 11:03:02 INFO - ##### 11:03:02 INFO - Running pre-action listener: _resource_record_pre_action 11:03:02 INFO - Running main action method: run_tests 11:03:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:03:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:03:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:03:02 INFO - 2016-07-09 11:03:02.288 screenresolution[2019:24653] starting screenresolution argv=screenresolution get 11:03:02 INFO - 2016-07-09 11:03:02.291 screenresolution[2019:24653] Display 0: 1600x1200x32@60 11:03:02 INFO - 2016-07-09 11:03:02.296 screenresolution[2020:24655] starting screenresolution argv=screenresolution list 11:03:02 INFO - Available Modes on Display 0 11:03:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:03:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:03:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:03:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:03:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:03:02 INFO - Intel Iris: 11:03:02 INFO - Chipset Model: Intel Iris 11:03:02 INFO - Type: GPU 11:03:02 INFO - Bus: Built-In 11:03:02 INFO - VRAM (Dynamic, Max): 1536 MB 11:03:02 INFO - Vendor: Intel (0x8086) 11:03:02 INFO - Device ID: 0x0a2e 11:03:02 INFO - Revision ID: 0x0009 11:03:02 INFO - Displays: 11:03:02 INFO - Display: 11:03:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:03:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:03:02 INFO - Display Serial Number: 1600x1200 60 11:03:02 INFO - Main Display: Yes 11:03:02 INFO - Mirror: Off 11:03:02 INFO - Online: Yes 11:03:02 INFO - Rotation: Supported 11:03:02 INFO - Return code: 0 11:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:03:02 INFO - Python 2.7.3 11:03:02 INFO - Return code: 0 11:03:02 INFO - grabbing minidump binary from tooltool 11:03:02 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://pvtbuilds.pvt.build.mozilla.org', 'pvtbuilds.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org'), ('https://queue.taskcluster.net', 'queue.taskcluster.net')]} 11:03:02 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 0x100e33d30>, '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 0x100b98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100e2bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:03:02 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 11:03:02 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 11:03:02 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 11:03:02 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:03:02 INFO - Return code: 0 11:03:02 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:03:02 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:03:02 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:03:02 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:03:02 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:03:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 11:03:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0073 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 11:03:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.210XvjmTW2/Render', 11:03:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:03:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:03:02 INFO - 'HOME': '/Users/cltbld', 11:03:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:03:02 INFO - 'LOGNAME': 'cltbld', 11:03:02 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:03:02 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:03:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:03:02 INFO - 'MOZ_NO_REMOTE': '1', 11:03:02 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:03:02 INFO - 'NO_EM_RESTART': '1', 11:03:02 INFO - 'PAGER': '/bin/cat', 11:03:02 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:03:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:03:02 INFO - 'PWD': '/builds/slave/test', 11:03:02 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:03:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:03:02 INFO - 'SHELL': '/bin/bash', 11:03:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.j7XcVKQD7a/Listeners', 11:03:02 INFO - 'TMPDIR': '/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/', 11:03:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:03:02 INFO - 'USER': 'cltbld', 11:03:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:03:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:03:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:03:02 INFO - 'XPC_FLAGS': '0x0', 11:03:02 INFO - 'XPC_SERVICE_NAME': '0', 11:03:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:03:02 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0073', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 11:03:02 INFO - mozversion application_buildid: 20160709090026 11:03:02 INFO - mozversion application_changeset: 679118259e91f40d4a8f968f03ec4cff066cdb5b 11:03:02 INFO - mozversion application_display_name: Nightly 11:03:02 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:03:02 INFO - mozversion application_name: Firefox 11:03:02 INFO - mozversion application_remotingname: firefox 11:03:02 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 11:03:02 INFO - mozversion application_vendor: Mozilla 11:03:02 INFO - mozversion application_version: 50.0a1 11:03:02 INFO - mozversion platform_buildid: 20160709090026 11:03:02 INFO - mozversion platform_changeset: 679118259e91f40d4a8f968f03ec4cff066cdb5b 11:03:02 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 11:03:02 INFO - mozversion platform_version: 50.0a1 11:03:02 INFO - using testdate: 1468087382 11:03:02 INFO - actual date: 1468087382 11:03:02 INFO - starting webserver on 'localhost:52258' 11:03:02 INFO - SUITE-START | Running 2 tests 11:03:02 INFO - TEST-START | dromaeo_css 11:03:02 INFO - operating with platform_type : mac_ 11:03:02 INFO - Initialising browser for dromaeo_css test... 11:03:02 INFO - TEST-INFO | started process 2030 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmphkRTcH/profile http://localhost:52258/getInfo.html) 11:03:06 INFO - PROCESS | 2030 | __metrics Screen width/height:1600/1200 11:03:06 INFO - PROCESS | 2030 | colorDepth:24 11:03:06 INFO - PROCESS | 2030 | Browser inner width/height: 1024/689 11:03:06 INFO - PROCESS | 2030 | __metrics 11:03:06 ERROR - PROCESS | 2030 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 11:03:06 INFO - TEST-INFO | 2030: exit 0 11:03:06 INFO - Browser initialized. 11:03:06 INFO - Running cycle 1/1 for dromaeo_css test... 11:03:06 INFO - TEST-INFO | started process 2033 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmphkRTcH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:03:18 INFO - PROCESS | 2033 | RSS: Main: 254861312 11:03:18 INFO - PROCESS | 2033 | 11:04:08 INFO - PROCESS | 2033 | 1468087448475 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:06:19 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:52258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52258/tests/dromaeo/cssquery-ext.html) 11:06:20 INFO - PROCESS | 2033 | RSS: Main: 269025280 11:06:20 INFO - PROCESS | 2033 | 11:09:23 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:52258/tests/dromaeo/cssquery-ext.html (next: http://localhost:52258/tests/dromaeo/cssquery-jquery.html) 11:09:23 INFO - PROCESS | 2033 | RSS: Main: 277422080 11:09:23 INFO - PROCESS | 2033 | 11:12:25 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:52258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52258/tests/dromaeo/cssquery-mootools.html) 11:12:26 INFO - PROCESS | 2033 | RSS: Main: 281964544 11:12:26 INFO - PROCESS | 2033 | 11:15:28 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:52258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52258/tests/dromaeo/cssquery-prototype.html) 11:15:28 INFO - PROCESS | 2033 | RSS: Main: 280915968 11:15:28 INFO - PROCESS | 2033 | 11:18:31 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:52258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52258/tests/dromaeo/cssquery-yui.html) 11:18:32 INFO - PROCESS | 2033 | RSS: Main: 284692480 11:18:32 INFO - PROCESS | 2033 | 11:21:34 INFO - PROCESS | 2033 | Cycle 1(1): loaded http://localhost:52258/tests/dromaeo/cssquery-yui.html (next: http://localhost:52258/tests/dromaeo/cssquery-dojo.html) 11:21:35 INFO - PROCESS | 2033 | RSS: Main: 284930048 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | __start_tp_report 11:21:35 INFO - PROCESS | 2033 | _x_x_mozilla_page_load 11:21:35 INFO - PROCESS | 2033 | _x_x_mozilla_page_load_details 11:21:35 INFO - PROCESS | 2033 | |i|pagename|runs| 11:21:35 INFO - PROCESS | 2033 | |0;dojo.html;3320;3320.3027364253508;3349.966500334996;3390.2541440883006;3423.0757695422235;5655.575831812615;5771.394003629622;5807.970960145199;5917;5928.733207005685;5717.542596592273;5769.798057068004;5822.184894114816;5835;5865.736041878115;5150.944056468425;5194;5277.762500687479;5282.128448805943;5324.813631522898;242.19982387517464;5156.381234251876;5185.637005409623;5268.157094864822;5296.523312901838;5172.671848651441;5214.165733482625;5216.26972223889;5234.162533994542;5259.31628888244;4325.675574331937;4879.804447910285;5243.842684719464;5269.789208431658;5271.710055946922;5145.922811157836;5152.304438900738;5166.147585648401;5257.921131182997;5273.209018647195;5621.241132447109;5820.941790582082;5821.09772985188;5840.269966254218;5882.147088672119;6269.216347956505;6307.905381419282;6311.905321420182;6322.083297921821;6333.746650134035;5391.757370918279;6223.0354295084335;6232.34560371159;6279.152314437538;6308.495320374359;5910.615809972338;5977.91033134499;5981.342052374235;5987.13186587951;6022.909656355158;6214.906776398358;6355.713992870332;6375.6174629522375;6377.71300291488;6397.808065758035;6145.508359331243;6149.877002459926;6154.599951003171;6206.937930620727;6228.065790131516;6181.196444462191;6265.65538895356;6295.905561416583;6296.464800491918;6308.337624549448;6214.316425193225;6221.813345599639;6259.436650701458;6305.590136641195;6311.431971122621;6250.781222657276;6267.122602835607;6269.279032911182;6273.309935907047;6283.434490895841;4113.239050775616;4381.605655491021;4986.227134794112;5007.399112106497;5038.496150384932;4374.475062992397;4422.800973956179;4448.866534003985;4463.4420697412825;4505.121501307279;4700.271457924027;5865.35481097079;5943.5839491236065;6029;6049.334573196945;6251.3123556408755;6288.591241569284;6295.748170073238;6322.304546499881;6385.297617262097;5938.435848594377;5973.880522389528;5976.5218782497295;6020.608660437058;6074.149619053336;5431.321084864392;5516.089845175581;5520.171974203901;5526.281583394133;5533.3083364579425;6326.620402775849;6370.8088757337355;6394.9680251599375;6411.038344248307;6452.935470645421;6283.5601507895835;6283.842903927255;6284.968575157095;6294.496440284858;6315.5894866833505;6258.0925765762795;6457.063725759833;6485.3514648534765;6487.935120648733;6528.543001989815;6013.218281623361;6131.682521312785;6141.263048434216;6177.938220617736;6224.813255602339;5506.531944784578;6231.813045608639;6241;6242.219722534684;6262.123302737711;6132.264128304632;6151.477124444473;6159.076138579249;6167.84580385494;6179.196704428396;5219.4780521947505;6143.600665956698;6257.655828929453;6277.843053923689;6286.874262514632;6033.456988871023;6086.7260973255425;6111.602745821508;6131.356207598138;6148.907766383597;6144.262688477233;6178.227721534809;6187.474064704551;6232.563720539519;6246.406591373812;6084.404291473306;6147.016477363601;6156.722947467285;6166.445019948226;6167.969160154171 11:21:35 INFO - PROCESS | 2033 | |1;ext.html;491.33424210195176;520.4067363205944;521.5306224398043;524.4414698346261;559.4936582392936;36611.81694091529;38896.80551597242;39008.99999999996;39011.609883901154;39122.60877391225;37649;39285.41071883924;39969.400458993136;40114.59885401145;40122.39816402756;9332.766680833;9507.47708876015;9507.857382139273;9512.952435237814;9516.80966380672;9412.670556530522;9461.290403219786;9475.857862132074;9486.905130948706;9508.667196648119;9495.905040949605;9535.856962145539;9543.284253681002;9547.904520954806;9555.044495550459;9454.952725236364;9513.952430237805;9526.618935242583;9529.809403811954;9543.761405964837;9437.905620943771;9455.952720236424;9476.147146756824;9497.050294970448;9508.049195080506;37843.24313513714;38231.61768382308;38318.80840595779;38346.80826595877;38870.22259554821;3624.1845584743223;3704.907377315562;3732.981335093334;3810.580836108026;4076.408920706511;30227.546586801214;31015.534766978515;31352.529712054336;31364.902228422125;31680.89116880898;3170.359809105121;3300.7359411247285;3346.498025296201;3360.7479439041927;3362.815045172539;311358;318574.4071279652;363607;370252;372750;27073.458530829274;34327;34412.82793586066;58971.41028589745;59072.7046364774;34888.651113489046;34977.65022349795;35217.295654086774;35423;35452.468212976826;14746.188959607325;14935.925320373546;14958.62603434901;14984.925075374553;14996.725278351245;12678.936605316914;12802.487900484062;12816.974642028616;12838.716128387274;12839.935800320938;7634.4655874088285;7654.540727556365;7667.769966901002;7693.692252309845;7709.0749110107145;2788.776897848167;2808.719128087175;3236.093893709765;3267.803931764102;3299.554560134351;935.9064093590723;945.4847108325979;987.0524296675129;990.8018396320765;1050.4747626186906;28390.574141387897;29900;30171.849140754595;30795;31586.684133158833;961.927855410847;1020.2654089055868;1110.6334909479851;1113.8997490225918;1178.3369584738552;13663.931680341733;15408.383664653513;15590.9220453897;15610.375584976473;15982.680346393006;593290;653210;671172;672599;674977;57175;57508;57955;57980.420195798346;58080.709596452594;1373.7321222361743;1381.647679841634;1428.8999770015998;1430.2276770543997;1436.2172615924137;25154.61031202033;29831.552526712552;30015.849920750257;30038.54942175913;30186.24534386658;51229.231561527355;51814.48185518247;51840.222396663325;52536;52611;621244;645186;646944;647196;650210;26284.080057197905;26347.604785927844;26621;26658.466830663667;26684.332891677863;46859.53140468688;46918.76540617275;47110;47824;48387.27419088787;38354;38848;38906.416403754534;39179.41230881482;39683;38798;39203.019924502114;39489.81530554088;39600;40618.98452538592;38135.42796857994;38501.6149838498;39148.217035658556;39304.410433842946;41252.58747412488;26784.59823102616;29189.562156567245;32041.67958320387;34718.37947118255;35571;26121.346966325993;26243.212703618923;26319;26607.20178394651;26750.197494075208 11:21:35 INFO - PROCESS | 2033 | |2;jquery.html;378.42290506976855;383.24883228871414;384.24495865624044;389.7583498231097;403.70932928291637;18509.981950992707;20389;20475.07862146203;20523.897380513095;20533.691994620094;19981;20015.999200040013;20796.376108716726;20891.37325880222;20988.16047358104;5264.684118952875;5765.7981970631035;5919.940800592003;5928.2293301870695;5941.04578275013;5868.559858010645;5875.7062146892695;5918.171455996164;5924.763009479637;5929.73316200772;5402.621816472849;5769.1923130761725;5770.653760774367;5854.590178687494;5878.118282257674;5678.375378708339;5793.768249270024;5816.4474374934525;5827.35899051104;5846.619969701977;4723.881902952454;5065.8733531662;5067.417247016603;5070.974645126787;5084.923726144111;23190.3042908713;24333.756662433327;24570.385740356454;24671.62992555113;24856.87571562149;2440.707115146176;2471.1721573272976;2840.3751174741524;3001.189678786737;3075.138961090898;15024.774628380583;15189.848101518954;15192.924035379863;15311.31099100532;15620.140892250922;1745.0751101916005;1811.3799973172086;1835.4677143628332;1934.2553117049897;1958.8922609256485;98850.50574747153;101104.49447752787;101926.49036754842;102495.46256806079;102781.45827812514;9150.35947483683;11238.943805280922;11432;11830.408479575986;11893.464794084288;14490.203038832966;14651;14799.482018129314;14875.404983800527;14944.402223910922;7138.286171382924;8561.272291855137;8801.779955501164;8878.600462979182;8967.237784788365;7245.275472452712;8833.558322083998;8834.734957951272;8905.777355566162;9011.729648110568;4570.908581828345;4616;4650.4186976627925;4711.363965864564;4738.64460165489;1679.5213364191147;1689.4678176374648;1953.540917884296;1982;2001.2895422125227;716.7563028570311;766.8880123820371;867.7179916527027;872.0625327772669;984.4191926763101;20764.1694332225;21035.368938931857;21334.35996920064;21338.359849204237;21493.570128597654;837.656560810065;946.8343039967978;955.3551352836946;992.8957459466797;1000.7097941596855;10368.792624147474;10531.104856087166;11115.555377784798;11139.275947063416;11141.108711303077;569704;594913;598668;613552;622631;131257;133636;133973.99039014423;135160;136606;944.4333399959969;960.7790208252142;963.3160456076124;963.629002833914;976.067855198284;18186.36347727823;18644.533886652942;18803.905980470012;18924.580656451097;19018.144183512053;52115.73942130265;54368.728156358964;54438.727806360715;54518.72740636271;55219.72390138024;75364.62317688376;80971.78542321752;81883.5905820467;82300.17699822925;82508.58745706233;8083.690441150916;8102.311303539265;8103.8379232413845;8265.008199016156;8370.581470926307;11361.886381136082;12303.9384803079;12635.557755478514;12680;12710.110292279453;10755.193360497837;10910.74526429439;10918.72703182395;11033.61382351646;11035.22753407286;9522;10728.839067414152;10817.621383251548;10960.561577537128;11009.119270458505;10989.560417583207;11088.556457741923;11160;11241;11258.268212566158;8135.75592732219;8174.877376839233;8195.672173113247;8226.506409615204;8313.168683131638;9438.716838494856;10055.597776088875;10667.786644267226;10996.615118470816;11037.061849742862 11:21:35 INFO - PROCESS | 2033 | |3;mootools.html;1559.5945054285885;1604.1417841454822;1836.2471386731443;1927.1135277772216;1929.1608150454563;7468.365188958938;7862.567558784264;8442.535660538668;8919.15268049536;8922.91077089229;8553.871691924625;8638.956805215965;8705.085965973576;8731.432456890298;8732.432391894537;2159.7408311002623;2219.4340443186966;2350.999999999996;2371.312319427364;2376.4415362389823;2173.3371321746863;2198.395441253652;2388.399772152653;2391.7727815857465;2396.6405039244078;2192.8597129492655;2227.1425501182;2337.1819863047967;2355.9764402356013;2374.27584586701;2331.591971405014;2338.3101984914406;2345.0971376020184;2353.1999120299097;2358.917437889674;1953.384683824605;1955.8337541309006;2333.556624241389;2335.7781010803947;2340.8946597403155;8688.391812573122;8825.691100811506;8844.69043583478;8847.823043539092;8870.778230544289;5126.5642420394315;5269.552088072518;5286.81496147633;5290.100682883919;5292.285541451894;3165;3472.8958131256104;3565.750397472178;3628.2924829658227;3654.5979942206336;1088.8965548272981;1090.2695194219891;1093.1200383691125;1098.6099934523288;1116.4975760907623;9629.614815407374;11355.432228388547;11484.483198256099;11493.712657183554;11538.480768365444;4236.046889449849;4581.106684196549;4627.884302892455;4778.211595086841;4816.253480710496;9276.11876871696;9749.756256093513;9756.90243097574;9767.902320976842;9783.217342612574;5314.3091398117995;5320.015797077553;5332.280142180756;5350.545203657655;5361.410244873061;4372.125574885035;4463.7991290391365;4888.926666100011;4904.852854414302;4914.262860570871;3923.803809809498;3939.803009849496;3998.2403343364667;4018.6985976051915;4082.3264161413626;1288.7809072457517;1289.5873320537526;1290.9160904541175;1297.1438850358718;1306.7190554030929;911.7264820553808;935.4153653966271;938.2822141062093;943.8867335919733;955.4553904274496;5151.665141765773;5868.911966320508;5958.463738263577;6059.36376680451;6096.451319381277;417.8433087592153;437.7351702220175;438.3709377043952;448.3745175480187;457.0972329648917;4048.08917993455;4072.898177545526;4089.9161722143654;4112.547619761824;4114.321137012359;10271.126954208976;10648.14814814813;10649.89350106489;10657.413842238751;10661.147108231324;10483.895161048444;10556.944305569381;10645.095166910745;10690.037896589345;10740.30188037775;8149.388795840336;8246.83506329882;8248.711295104642;8250.628721707537;8262.339012878956;5635.6618602883955;5776.451237132465;5850.23946886902;5903.793367232126;5920.644761314335;9832.60669573209;9835.803283934425;9863.260255480724;9866.851997219905;9921.65274215399;8177.918220817715;9764.85352719724;10052.949735251277;10206;10217.182625389802;6049;6864;7434.962825185839;7449.6275186241555;7487.2138425464545;8428.578571071299;8436.789080273042;8500.192481714228;8503.74488765338;8520.275776559061;6170.136180934763;6891.724331026703;7263.927360726325;7408.851822963618;7661.080670319598;5841.737121829443;6047.969760151171;6199.907001395073;7468.738594149396;7514.774556763306;6983.336583024605;6989.056477375692;7039.979203015431;7057.823554411181;7094.432445404458;4963.751812409438;5002.974985125051;5010.49895010496;5127.333446651912;5163.173892177234;4410.250257456304;5027.924581131213;5041.193409054534;5080.161773307363;5104.464031276662 11:21:35 INFO - PROCESS | 2033 | |4;prototype.html;305.9066984569706;310.45205212799414;312.4001916320664;313.2903972502281;316.0959655385598;4512.9548704512945;4655.30170474429;5601.6358936669085;5673.886522269552;5674.716264186794;4573.954260457394;4850.490698476662;5496.038193316163;5528.115501519757;5584.888302233953;3215.9839200803963;4007.559168491478;4109.609587089221;4145.834166633331;4206.516250631186;3750.643688849555;3793.525809273841;4190.099128687331;4216.704830661855;4222.197782421345;4156.044109854735;4201.3697945308295;4203.558626344236;4204.453421055258;4231.51896836108;3792.317382871096;4124.566920473337;4186.58134186582;4199;4248.4477017987465;3592.9281414371826;3603.891883243507;3621.7645853019735;3644.69020133289;3655.670989610948;5432.984693495108;5447.400785913546;5448.83653490396;5485.917711234335;5519.60544265278;4711.290416432268;4771.403574553181;4875.902481950341;4887.926681099786;4890.193118135503;4914.68054576455;5639.407862174454;5714.571407144439;5867.501262392701;5903.793367232126;3347.0126312737557;4029.879103626896;4047.150098479323;4061.715679902408;4077.2049450357194;5110.616703747197;5416.850989378054;5819.854503637401;6175.475084617813;6268.561200715906;3672.540932383452;4063.4108054331955;4067.7966101694797;4075.7962101894786;4104.486939132608;3447.0696410009973;3875.980620096938;3933.1347103637154;3972.6226008529143;4165.58344165581;3806.0779306450067;3961.782101984361;4047.5547689754103;4867.221244600847;4876.707397556158;4549.931751023737;4812.542808433193;4914.92627610579;5967.492763115184;5971.775785963867;1097.665212110314;1394.4979807269374;1394.7350003499303;1397.9720405591831;1399.783033629789;840.3403328387309;844.907060223375;849.6261644876234;853.9573021349032;890.6214858685033;5415.106507426231;5462.432656651367;5468.043092458804;5474.507294343449;5525.530329921921;5779;5852.531797456193;5930.199423077829;5937.7921772737745;5961.406788047213;4397.273690311963;4532.818687252473;5068.746562671851;5433.864153396196;5489.7648029193115;2912.3788590268928;3144.9371012579622;3154.9684503154676;3163.3831402876676;3165.382750363657;5380.1929710542945;5953.1367951647635;6068.120122582201;6179.134921111048;6187.84530386744;5585.972070139705;5875.823725288248;6026.758929642853;6123.938760612336;6170.6914654268;4934.827281045146;6151.754609567475;6181.567290289637;6189.288231853395;6260.530460215593;5022.422421421583;5367.329083864517;5520.06158952971;5574.498295153456;5953.928292907145;5496.615236933376;5546.805861794818;5662.433756624305;5876.6180198286975;5931.940680593311;5748.080307151003;5766.509846663081;5851.239338885917;6171.876562468815;6177.6911154443;3407.2333724911696;3433.8454769534933;3917.29597625034;3952.7628342299554;3960.2475529649273;4738.431388233434;4793.880152996203;5482.91775623358;5984.1921340618455;5995.961373133156;5409.432009639088;5579.77680892776;5740.0815869460685;5802.83943211351;5832.24180856486;5271.262023316662;5744.770209191753;5760.625559338797;5806;5816.912746308724;4669.556392142741;4952.182889823139;5536.335639723098;5656.406077361982;5660.377358490645;5705.91734017089;5824.271966004249;5840.561957853263;5878.2064421304285;5878.794242201502;5706.714664266687;5712.685802280748;5745.511631511368;5752.367239603724;5755.971220143872 11:21:35 INFO - PROCESS | 2033 | |5;yui.html;745.4856149257013;768.1511929318103;777.5257093173167;809.959202424884;836.9958150209249;15111.622209444755;15684.451044213454;15849.762253566205;15861.524154275361;15868.920655396721;13739.175649461014;14543.418263269483;14603.342849571769;14624.926875365609;14628.34172462239;871.3247233394122;882.2677177942293;883.6244596046687;885.3758100539137;887.4453466583385;653.460894761821;675.9155105611799;891.0599317719802;898.8606765951255;899.3479727197762;935.0415823780611;951.4529145741192;953.0993211415181;953.3088510829663;957.1290125985355;941.8398872191709;945.394947233764;946.3659348236698;946.8011717539325;949.5632009275707;678.1049015299807;690.823839920817;702.2240424331143;706.3713295167305;709.5387997801419;15069.09585424878;15148;15148.772768408482;15172.620684482867;15179.013364131297;12131.514739410413;14641.780373294408;14745.631359216106;14752.262386880613;14897.47858824936;11931.642050738492;12194.268343899306;12417.503299867994;12446.377681115908;12497.250164990039;3808.809559522013;3811.923761524754;4003.719739618228;4021.8592349267633;4026;76901.23098768997;106065;106381.46809265981;106617.40073898897;107540;10951.34291942486;11039.282446640942;11347.432628368548;11454.770904581861;11579.305241685526;22696.546069078857;22951.655725164135;23144;23218.3034508965;23361.766382335958;6783.660816959133;8124.512529248264;8130.30892374143;8199.918000820035;8273.379496537784;4453.55464453552;4544.409226800495;4561.954380456153;4633.258678611406;4655.557722016403;3401.9319613608086;3474.5656792900886;3502.1594817243677;3511.94732079019;3514.947275790865;635.7584118035116;640.6444423344996;650.0574167457206;654.3293124547282;660.4320284555279;597.5966222799593;605.857623458487;638.2277444292365;658.4633523678187;669.531328070343;9770.609175632895;9770.853437198448;9771.019838949189;9771.609135634637;9889.703308900744;576.5358886096733;583.3845293215699;694.3681250062419;696.3036963036963;699.5627732667083;3753.3619284721663;4617.261238201939;4721.291806229025;4721.575058244705;4722.504137065627;121072.39463802625;144844;146597;147898;149095;114615.42692286485;117539;126323;128686.13941581767;130323;110956;112796.43601782103;113837;114713;115854;9110.316726245426;9150.313726470407;9197.86203206939;9212.401193922373;9251.583678734607;48806.267905982146;56259.718701407866;61561.69219154055;63022.684886575276;63256.36743632505;81087.78368324597;81618;82619;89150.10849891677;89428.6585701228;13165.341732913508;13836.377363018488;13897.166170029426;13992.510262140777;14219.50231741884;13510.932445338103;14262.800493681725;14915.477958271842;15175.165365904539;15373.615659608598;12151.75696486083;13918.860811392162;13965;14232.288385580887;14362.13827170373;13802.723945521235;13995.790063148857;14616.415343386145;14631.048981816148;14695.338709757872;13927.442902283796;14049.438022478986;14074.929625351808;14117.08238964464;14575.854241457873;11458.713532161762;11459.828102578635;11552.537898483966;11639.709007274885;11926.821097683369;10295.661563996931;10970.561177553127;11748.353840538852;11774.234674746456;11774.705632359259 11:21:35 INFO - PROCESS | 2033 | __end_tp_report 11:21:35 INFO - PROCESS | 2033 | __start_cc_report 11:21:35 INFO - PROCESS | 2033 | _x_x_mozilla_cycle_collect,432 11:21:35 INFO - PROCESS | 2033 | __end_cc_report 11:21:35 INFO - PROCESS | 2033 | __startTimestamp1468088495002__endTimestamp 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | ------- Summary: start ------- 11:21:35 INFO - PROCESS | 2033 | Number of tests: 6 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | [#0] dojo.html Cycles:165 Average:5758.80 Median:6111.60 stddev:812.18 (13.3%) stddev-sans-first:791.82 11:21:35 INFO - PROCESS | 2033 | Values: 3320.0 3320.3 3350.0 3390.3 3423.1 5655.6 5771.4 5808.0 5917.0 5928.7 5717.5 5769.8 5822.2 5835.0 5865.7 5150.9 5194.0 5277.8 5282.1 5324.8 242.2 5156.4 5185.6 5268.2 5296.5 5172.7 5214.2 5216.3 5234.2 5259.3 4325.7 4879.8 5243.8 5269.8 5271.7 5145.9 5152.3 5166.1 5257.9 5273.2 5621.2 5820.9 5821.1 5840.3 5882.1 6269.2 6307.9 6311.9 6322.1 6333.7 5391.8 6223.0 6232.3 6279.2 6308.5 5910.6 5977.9 5981.3 5987.1 6022.9 6214.9 6355.7 6375.6 6377.7 6397.8 6145.5 6149.9 6154.6 6206.9 6228.1 6181.2 6265.7 6295.9 6296.5 6308.3 6214.3 6221.8 6259.4 6305.6 6311.4 6250.8 6267.1 6269.3 6273.3 6283.4 4113.2 4381.6 4986.2 5007.4 5038.5 4374.5 4422.8 4448.9 4463.4 4505.1 4700.3 5865.4 5943.6 6029.0 6049.3 6251.3 6288.6 6295.7 6322.3 6385.3 5938.4 5973.9 5976.5 6020.6 6074.1 5431.3 5516.1 5520.2 5526.3 5533.3 6326.6 6370.8 6395.0 6411.0 6452.9 6283.6 6283.8 6285.0 6294.5 6315.6 6258.1 6457.1 6485.4 6487.9 6528.5 6013.2 6131.7 6141.3 6177.9 6224.8 5506.5 6231.8 6241.0 6242.2 6262.1 6132.3 6151.5 6159.1 6167.8 6179.2 5219.5 6143.6 6257.7 6277.8 6286.9 6033.5 6086.7 6111.6 6131.4 6148.9 6144.3 6178.2 6187.5 6232.6 6246.4 6084.4 6147.0 6156.7 6166.4 6168.0 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | [#1] ext.html Cycles:180 Average:66637.28 Median:38649.81 stddev:152114.08 (393.6%) stddev-sans-first:152459.73 11:21:35 INFO - PROCESS | 2033 | Values: 491.3 520.4 521.5 524.4 559.5 36611.8 38896.8 39009.0 39011.6 39122.6 37649.0 39285.4 39969.4 40114.6 40122.4 9332.8 9507.5 9507.9 9513.0 9516.8 9412.7 9461.3 9475.9 9486.9 9508.7 9495.9 9535.9 9543.3 9547.9 9555.0 9455.0 9514.0 9526.6 9529.8 9543.8 9437.9 9456.0 9476.1 9497.1 9508.0 37843.2 38231.6 38318.8 38346.8 38870.2 3624.2 3704.9 3733.0 3810.6 4076.4 30227.5 31015.5 31352.5 31364.9 31680.9 3170.4 3300.7 3346.5 3360.7 3362.8 311358.0 318574.4 363607.0 370252.0 372750.0 27073.5 34327.0 34412.8 58971.4 59072.7 34888.7 34977.7 35217.3 35423.0 35452.5 14746.2 14935.9 14958.6 14984.9 14996.7 12678.9 12802.5 12817.0 12838.7 12839.9 7634.5 7654.5 7667.8 7693.7 7709.1 2788.8 2808.7 3236.1 3267.8 3299.6 935.9 945.5 987.1 990.8 1050.5 28390.6 29900.0 30171.8 30795.0 31586.7 961.9 1020.3 1110.6 1113.9 1178.3 13663.9 15408.4 15590.9 15610.4 15982.7 593290.0 653210.0 671172.0 672599.0 674977.0 57175.0 57508.0 57955.0 57980.4 58080.7 1373.7 1381.6 1428.9 1430.2 1436.2 25154.6 29831.6 30015.8 30038.5 30186.2 51229.2 51814.5 51840.2 52536.0 52611.0 621244.0 645186.0 646944.0 647196.0 650210.0 26284.1 26347.6 26621.0 26658.5 26684.3 46859.5 46918.8 47110.0 47824.0 48387.3 38354.0 38848.0 38906.4 39179.4 39683.0 38798.0 39203.0 39489.8 39600.0 40619.0 38135.4 38501.6 39148.2 39304.4 41252.6 26784.6 29189.6 32041.7 34718.4 35571.0 26121.3 26243.2 26319.0 26607.2 26750.2 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | [#2] jquery.html Cycles:180 Average:34955.61 Median:3038.16 stddev:99991.63 (3291.2%) stddev-sans-first:100238.43 11:21:35 INFO - PROCESS | 2033 | Values: 378.4 383.2 384.2 389.8 403.7 18510.0 20389.0 20475.1 20523.9 20533.7 19981.0 20016.0 20796.4 20891.4 20988.2 5264.7 5765.8 5919.9 5928.2 5941.0 5868.6 5875.7 5918.2 5924.8 5929.7 5402.6 5769.2 5770.7 5854.6 5878.1 5678.4 5793.8 5816.4 5827.4 5846.6 4723.9 5065.9 5067.4 5071.0 5084.9 23190.3 24333.8 24570.4 24671.6 24856.9 2440.7 2471.2 2840.4 3001.2 3075.1 15024.8 15189.8 15192.9 15311.3 15620.1 1745.1 1811.4 1835.5 1934.3 1958.9 98850.5 101104.5 101926.5 102495.5 102781.5 9150.4 11238.9 11432.0 11830.4 11893.5 14490.2 14651.0 14799.5 14875.4 14944.4 7138.3 8561.3 8801.8 8878.6 8967.2 7245.3 8833.6 8834.7 8905.8 9011.7 4570.9 4616.0 4650.4 4711.4 4738.6 1679.5 1689.5 1953.5 1982.0 2001.3 716.8 766.9 867.7 872.1 984.4 20764.2 21035.4 21334.4 21338.4 21493.6 837.7 946.8 955.4 992.9 1000.7 10368.8 10531.1 11115.6 11139.3 11141.1 569704.0 594913.0 598668.0 613552.0 622631.0 131257.0 133636.0 133974.0 135160.0 136606.0 944.4 960.8 963.3 963.6 976.1 18186.4 18644.5 18803.9 18924.6 19018.1 52115.7 54368.7 54438.7 54518.7 55219.7 75364.6 80971.8 81883.6 82300.2 82508.6 8083.7 8102.3 8103.8 8265.0 8370.6 11361.9 12303.9 12635.6 12680.0 12710.1 10755.2 10910.7 10918.7 11033.6 11035.2 9522.0 10728.8 10817.6 10960.6 11009.1 10989.6 11088.6 11160.0 11241.0 11258.3 8135.8 8174.9 8195.7 8226.5 8313.2 9438.7 10055.6 10667.8 10996.6 11037.1 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | [#3] mootools.html Cycles:180 Average:5608.49 Median:5019.21 stddev:3141.89 (62.6%) stddev-sans-first:3135.97 11:21:35 INFO - PROCESS | 2033 | Values: 1559.6 1604.1 1836.2 1927.1 1929.2 7468.4 7862.6 8442.5 8919.2 8922.9 8553.9 8639.0 8705.1 8731.4 8732.4 2159.7 2219.4 2351.0 2371.3 2376.4 2173.3 2198.4 2388.4 2391.8 2396.6 2192.9 2227.1 2337.2 2356.0 2374.3 2331.6 2338.3 2345.1 2353.2 2358.9 1953.4 1955.8 2333.6 2335.8 2340.9 8688.4 8825.7 8844.7 8847.8 8870.8 5126.6 5269.6 5286.8 5290.1 5292.3 3165.0 3472.9 3565.8 3628.3 3654.6 1088.9 1090.3 1093.1 1098.6 1116.5 9629.6 11355.4 11484.5 11493.7 11538.5 4236.0 4581.1 4627.9 4778.2 4816.3 9276.1 9749.8 9756.9 9767.9 9783.2 5314.3 5320.0 5332.3 5350.5 5361.4 4372.1 4463.8 4888.9 4904.9 4914.3 3923.8 3939.8 3998.2 4018.7 4082.3 1288.8 1289.6 1290.9 1297.1 1306.7 911.7 935.4 938.3 943.9 955.5 5151.7 5868.9 5958.5 6059.4 6096.5 417.8 437.7 438.4 448.4 457.1 4048.1 4072.9 4089.9 4112.5 4114.3 10271.1 10648.1 10649.9 10657.4 10661.1 10483.9 10556.9 10645.1 10690.0 10740.3 8149.4 8246.8 8248.7 8250.6 8262.3 5635.7 5776.5 5850.2 5903.8 5920.6 9832.6 9835.8 9863.3 9866.9 9921.7 8177.9 9764.9 10052.9 10206.0 10217.2 6049.0 6864.0 7435.0 7449.6 7487.2 8428.6 8436.8 8500.2 8503.7 8520.3 6170.1 6891.7 7263.9 7408.9 7661.1 5841.7 6048.0 6199.9 7468.7 7514.8 6983.3 6989.1 7040.0 7057.8 7094.4 4963.8 5003.0 5010.5 5127.3 5163.2 4410.3 5027.9 5041.2 5080.2 5104.5 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | [#4] prototype.html Cycles:180 Average:4656.30 Median:5394.81 stddev:1447.45 (26.8%) stddev-sans-first:1414.20 11:21:35 INFO - PROCESS | 2033 | Values: 305.9 310.5 312.4 313.3 316.1 4513.0 4655.3 5601.6 5673.9 5674.7 4574.0 4850.5 5496.0 5528.1 5584.9 3216.0 4007.6 4109.6 4145.8 4206.5 3750.6 3793.5 4190.1 4216.7 4222.2 4156.0 4201.4 4203.6 4204.5 4231.5 3792.3 4124.6 4186.6 4199.0 4248.4 3592.9 3603.9 3621.8 3644.7 3655.7 5433.0 5447.4 5448.8 5485.9 5519.6 4711.3 4771.4 4875.9 4887.9 4890.2 4914.7 5639.4 5714.6 5867.5 5903.8 3347.0 4029.9 4047.2 4061.7 4077.2 5110.6 5416.9 5819.9 6175.5 6268.6 3672.5 4063.4 4067.8 4075.8 4104.5 3447.1 3876.0 3933.1 3972.6 4165.6 3806.1 3961.8 4047.6 4867.2 4876.7 4549.9 4812.5 4914.9 5967.5 5971.8 1097.7 1394.5 1394.7 1398.0 1399.8 840.3 844.9 849.6 854.0 890.6 5415.1 5462.4 5468.0 5474.5 5525.5 5779.0 5852.5 5930.2 5937.8 5961.4 4397.3 4532.8 5068.7 5433.9 5489.8 2912.4 3144.9 3155.0 3163.4 3165.4 5380.2 5953.1 6068.1 6179.1 6187.8 5586.0 5875.8 6026.8 6123.9 6170.7 4934.8 6151.8 6181.6 6189.3 6260.5 5022.4 5367.3 5520.1 5574.5 5953.9 5496.6 5546.8 5662.4 5876.6 5931.9 5748.1 5766.5 5851.2 6171.9 6177.7 3407.2 3433.8 3917.3 3952.8 3960.2 4738.4 4793.9 5482.9 5984.2 5996.0 5409.4 5579.8 5740.1 5802.8 5832.2 5271.3 5744.8 5760.6 5806.0 5816.9 4669.6 4952.2 5536.3 5656.4 5660.4 5705.9 5824.3 5840.6 5878.2 5878.8 5706.7 5712.7 5745.5 5752.4 5756.0 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - PROCESS | 2033 | [#5] yui.html Cycles:180 Average:24255.72 Median:3488.36 stddev:37927.54 (1087.3%) stddev-sans-first:37992.86 11:21:35 INFO - PROCESS | 2033 | Values: 745.5 768.2 777.5 810.0 837.0 15111.6 15684.5 15849.8 15861.5 15868.9 13739.2 14543.4 14603.3 14624.9 14628.3 871.3 882.3 883.6 885.4 887.4 653.5 675.9 891.1 898.9 899.3 935.0 951.5 953.1 953.3 957.1 941.8 945.4 946.4 946.8 949.6 678.1 690.8 702.2 706.4 709.5 15069.1 15148.0 15148.8 15172.6 15179.0 12131.5 14641.8 14745.6 14752.3 14897.5 11931.6 12194.3 12417.5 12446.4 12497.3 3808.8 3811.9 4003.7 4021.9 4026.0 76901.2 106065.0 106381.5 106617.4 107540.0 10951.3 11039.3 11347.4 11454.8 11579.3 22696.5 22951.7 23144.0 23218.3 23361.8 6783.7 8124.5 8130.3 8199.9 8273.4 4453.6 4544.4 4562.0 4633.3 4655.6 3401.9 3474.6 3502.2 3511.9 3514.9 635.8 640.6 650.1 654.3 660.4 597.6 605.9 638.2 658.5 669.5 9770.6 9770.9 9771.0 9771.6 9889.7 576.5 583.4 694.4 696.3 699.6 3753.4 4617.3 4721.3 4721.6 4722.5 121072.4 144844.0 146597.0 147898.0 149095.0 114615.4 117539.0 126323.0 128686.1 130323.0 110956.0 112796.4 113837.0 114713.0 115854.0 9110.3 9150.3 9197.9 9212.4 9251.6 48806.3 56259.7 61561.7 63022.7 63256.4 81087.8 81618.0 82619.0 89150.1 89428.7 13165.3 13836.4 13897.2 13992.5 14219.5 13510.9 14262.8 14915.5 15175.2 15373.6 12151.8 13918.9 13965.0 14232.3 14362.1 13802.7 13995.8 14616.4 14631.0 14695.3 13927.4 14049.4 14074.9 14117.1 14575.9 11458.7 11459.8 11552.5 11639.7 11926.8 10295.7 10970.6 11748.4 11774.2 11774.7 11:21:35 INFO - PROCESS | 2033 | -------- Summary: end -------- 11:21:35 INFO - PROCESS | 2033 | 11:21:35 INFO - TEST-INFO | 2033: exit 0 11:21:35 INFO - TEST-OK | dromaeo_css | took 1112734ms 11:21:35 INFO - TEST-START | kraken 11:21:35 INFO - operating with platform_type : mac_ 11:21:35 INFO - Initialising browser for kraken test... 11:21:35 INFO - TEST-INFO | started process 2039 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpxrZfaK/profile http://localhost:52258/getInfo.html) 11:21:38 INFO - PROCESS | 2039 | __metrics Screen width/height:1600/1200 11:21:38 INFO - PROCESS | 2039 | colorDepth:24 11:21:38 INFO - PROCESS | 2039 | Browser inner width/height: 1024/689 11:21:38 INFO - PROCESS | 2039 | __metrics 11:21:38 ERROR - PROCESS | 2039 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2964: TypeError: this.tabs is undefined 11:21:39 INFO - TEST-INFO | 2039: exit 0 11:21:39 INFO - Browser initialized. 11:21:39 INFO - Running cycle 1/1 for kraken test... 11:21:39 INFO - TEST-INFO | started process 2041 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/tmpxrZfaK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:21:51 INFO - PROCESS | 2041 | RSS: Main: 254459904 11:21:51 INFO - PROCESS | 2041 | 11:21:53 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52258/tests/kraken/driver.html?testName=audio-beat-detection) 11:21:54 INFO - PROCESS | 2041 | RSS: Main: 267165696 11:21:54 INFO - PROCESS | 2041 | 11:21:57 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52258/tests/kraken/driver.html?testName=audio-dft) 11:21:58 INFO - PROCESS | 2041 | RSS: Main: 271585280 11:21:58 INFO - PROCESS | 2041 | 11:22:01 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52258/tests/kraken/driver.html?testName=audio-fft) 11:22:02 INFO - PROCESS | 2041 | RSS: Main: 271536128 11:22:02 INFO - PROCESS | 2041 | 11:22:04 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52258/tests/kraken/driver.html?testName=audio-oscillator) 11:22:04 INFO - PROCESS | 2041 | RSS: Main: 272498688 11:22:04 INFO - PROCESS | 2041 | 11:22:06 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:22:07 INFO - PROCESS | 2041 | RSS: Main: 273223680 11:22:07 INFO - PROCESS | 2041 | 11:22:10 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52258/tests/kraken/driver.html?testName=imaging-darkroom) 11:22:11 INFO - PROCESS | 2041 | RSS: Main: 273502208 11:22:11 INFO - PROCESS | 2041 | 11:22:14 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52258/tests/kraken/driver.html?testName=imaging-desaturate) 11:22:14 INFO - PROCESS | 2041 | RSS: Main: 273653760 11:22:14 INFO - PROCESS | 2041 | 11:22:17 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52258/tests/kraken/driver.html?testName=json-parse-financial) 11:22:18 INFO - PROCESS | 2041 | RSS: Main: 273920000 11:22:18 INFO - PROCESS | 2041 | 11:22:20 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:22:20 INFO - PROCESS | 2041 | RSS: Main: 273539072 11:22:20 INFO - PROCESS | 2041 | 11:22:22 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:22:23 INFO - PROCESS | 2041 | RSS: Main: 273448960 11:22:23 INFO - PROCESS | 2041 | 11:22:25 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:22:26 INFO - PROCESS | 2041 | RSS: Main: 273833984 11:22:26 INFO - PROCESS | 2041 | 11:22:29 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:22:29 INFO - PROCESS | 2041 | RSS: Main: 273973248 11:22:29 INFO - PROCESS | 2041 | 11:22:32 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:22:33 INFO - PROCESS | 2041 | RSS: Main: 274063360 11:22:33 INFO - PROCESS | 2041 | 11:22:34 INFO - PROCESS | 2041 | Cycle 1(1): loaded http://localhost:52258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52258/tests/kraken/driver.html?testName=ai-astar) 11:22:35 INFO - PROCESS | 2041 | RSS: Main: 275062784 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | __start_tp_report 11:22:35 INFO - PROCESS | 2041 | _x_x_mozilla_page_load 11:22:35 INFO - PROCESS | 2041 | _x_x_mozilla_page_load_details 11:22:35 INFO - PROCESS | 2041 | |i|pagename|runs| 11:22:35 INFO - PROCESS | 2041 | |0;ai-astar;104;116;107;116;115;116;114;115;115;113 11:22:35 INFO - PROCESS | 2041 | |1;audio-beat-detection;148;127;146;152;110;131;131;110;155;109 11:22:35 INFO - PROCESS | 2041 | |2;audio-dft;200;222;195;209;279;277;199;209;231;199 11:22:35 INFO - PROCESS | 2041 | |3;audio-fft;68;86;77;155;88;84;68;58;132;61 11:22:35 INFO - PROCESS | 2041 | |4;audio-oscillator;70;72;121;72;71;85;113;71;73;110 11:22:35 INFO - PROCESS | 2041 | |5;imaging-gaussian-blur;90;83;77;133;136;94;91;89;95;83 11:22:35 INFO - PROCESS | 2041 | |6;imaging-darkroom;96;118;95;99;98;97;96;98;96;97 11:22:35 INFO - PROCESS | 2041 | |7;imaging-desaturate;84;146;79;106;142;150;82;143;146;91 11:22:35 INFO - PROCESS | 2041 | |8;json-parse-financial;58;58;55;57;72;69;58;58;55;57 11:22:35 INFO - PROCESS | 2041 | |9;json-stringify-tinderbox;67;73;69;78;79;84;75;70;70;73 11:22:35 INFO - PROCESS | 2041 | |10;stanford-crypto-aes;94;102;89;83;85;83;86;85;97;111 11:22:35 INFO - PROCESS | 2041 | |11;stanford-crypto-ccm;145;126;151;149;137;145;151;140;179;175 11:22:35 INFO - PROCESS | 2041 | |12;stanford-crypto-pbkdf2;163;165;162;163;166;162;163;165;166;162 11:22:35 INFO - PROCESS | 2041 | |13;stanford-crypto-sha256-iterative;59;86;85;85;86;84;71;69;67;68 11:22:35 INFO - PROCESS | 2041 | __end_tp_report 11:22:35 INFO - PROCESS | 2041 | __start_cc_report 11:22:35 INFO - PROCESS | 2041 | _x_x_mozilla_cycle_collect,1112 11:22:35 INFO - PROCESS | 2041 | __end_cc_report 11:22:35 INFO - PROCESS | 2041 | __startTimestamp1468088555198__endTimestamp 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | ------- Summary: start ------- 11:22:35 INFO - PROCESS | 2041 | Number of tests: 14 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#0] ai-astar Cycles:10 Average:113.10 Median:115.00 stddev:4.18 (3.6%) stddev-sans-first:2.85 11:22:35 INFO - PROCESS | 2041 | Values: 104.0 116.0 107.0 116.0 115.0 116.0 114.0 115.0 115.0 113.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#1] audio-beat-detection Cycles:10 Average:131.90 Median:138.50 stddev:17.97 (13.0%) stddev-sans-first:18.09 11:22:35 INFO - PROCESS | 2041 | Values: 148.0 127.0 146.0 152.0 110.0 131.0 131.0 110.0 155.0 109.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#2] audio-dft Cycles:10 Average:222.00 Median:215.50 stddev:31.56 (14.6%) stddev-sans-first:32.45 11:22:35 INFO - PROCESS | 2041 | Values: 200.0 222.0 195.0 209.0 279.0 277.0 199.0 209.0 231.0 199.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#3] audio-fft Cycles:10 Average:87.70 Median:72.50 stddev:31.61 (43.6%) stddev-sans-first:32.72 11:22:35 INFO - PROCESS | 2041 | Values: 68.0 86.0 77.0 155.0 88.0 84.0 68.0 58.0 132.0 61.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#4] audio-oscillator Cycles:10 Average:85.80 Median:71.50 stddev:20.54 (28.7%) stddev-sans-first:20.98 11:22:35 INFO - PROCESS | 2041 | Values: 70.0 72.0 121.0 72.0 71.0 85.0 113.0 71.0 73.0 110.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:89.50 stddev:20.47 (22.9%) stddev-sans-first:21.55 11:22:35 INFO - PROCESS | 2041 | Values: 90.0 83.0 77.0 133.0 136.0 94.0 91.0 89.0 95.0 83.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#6] imaging-darkroom Cycles:10 Average:99.00 Median:97.00 stddev:6.78 (7.0%) stddev-sans-first:7.11 11:22:35 INFO - PROCESS | 2041 | Values: 96.0 118.0 95.0 99.0 98.0 97.0 96.0 98.0 96.0 97.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#7] imaging-desaturate Cycles:10 Average:116.90 Median:114.50 stddev:30.96 (27.0%) stddev-sans-first:30.46 11:22:35 INFO - PROCESS | 2041 | Values: 84.0 146.0 79.0 106.0 142.0 150.0 82.0 143.0 146.0 91.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:58.00 stddev:5.85 (10.1%) stddev-sans-first:6.17 11:22:35 INFO - PROCESS | 2041 | Values: 58.0 58.0 55.0 57.0 72.0 69.0 58.0 58.0 55.0 57.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#9] json-stringify-tinderbox Cycles:10 Average:73.80 Median:74.00 stddev:5.27 (7.1%) stddev-sans-first:4.98 11:22:35 INFO - PROCESS | 2041 | Values: 67.0 73.0 69.0 78.0 79.0 84.0 75.0 70.0 70.0 73.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#10] stanford-crypto-aes Cycles:10 Average:91.50 Median:85.50 stddev:9.38 (11.0%) stddev-sans-first:9.91 11:22:35 INFO - PROCESS | 2041 | Values: 94.0 102.0 89.0 83.0 85.0 83.0 86.0 85.0 97.0 111.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#11] stanford-crypto-ccm Cycles:10 Average:149.80 Median:150.00 stddev:16.21 (10.8%) stddev-sans-first:17.10 11:22:35 INFO - PROCESS | 2041 | Values: 145.0 126.0 151.0 149.0 137.0 145.0 151.0 140.0 179.0 175.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:163.70 Median:164.00 stddev:1.64 (1.0%) stddev-sans-first:1.72 11:22:35 INFO - PROCESS | 2041 | Values: 163.0 165.0 162.0 163.0 166.0 162.0 163.0 165.0 166.0 162.0 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - PROCESS | 2041 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.00 Median:84.50 stddev:10.19 (12.1%) stddev-sans-first:8.75 11:22:35 INFO - PROCESS | 2041 | Values: 59.0 86.0 85.0 85.0 86.0 84.0 71.0 69.0 67.0 68.0 11:22:35 INFO - PROCESS | 2041 | -------- Summary: end -------- 11:22:35 INFO - PROCESS | 2041 | 11:22:35 INFO - TEST-INFO | 2041: exit 0 11:22:36 INFO - TEST-OK | kraken | took 60400ms 11:22:36 INFO - SUITE-END | took 1173s 11:22:36 INFO - Completed test suite (00:19:33) 11:22:36 INFO - javascript benchmark 11:22:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7281.679872447545, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3320.0, 3320.3027364253508, 3349.966500334996, 3390.2541440883006, 3423.0757695422235, 5655.575831812615, 5771.394003629622, 5807.970960145199, 5917.0, 5928.733207005685, 5717.542596592273, 5769.798057068004, 5822.184894114816, 5835.0, 5865.736041878115, 5150.944056468425, 5194.0, 5277.762500687479, 5282.128448805943, 5324.813631522898, 242.19982387517464, 5156.381234251876, 5185.637005409623, 5268.157094864822, 5296.523312901838, 5172.671848651441, 5214.165733482625, 5216.26972223889, 5234.162533994542, 5259.31628888244, 4325.675574331937, 4879.804447910285, 5243.842684719464, 5269.789208431658, 5271.710055946922, 5145.922811157836, 5152.304438900738, 5166.147585648401, 5257.921131182997, 5273.209018647195, 5621.241132447109, 5820.941790582082, 5821.09772985188, 5840.269966254218, 5882.147088672119, 6269.216347956505, 6307.905381419282, 6311.905321420182, 6322.083297921821, 6333.746650134035, 5391.757370918279, 6223.0354295084335, 6232.34560371159, 6279.152314437538, 6308.495320374359, 5910.615809972338, 5977.91033134499, 5981.342052374235, 5987.13186587951, 6022.909656355158, 6214.906776398358, 6355.713992870332, 6375.6174629522375, 6377.71300291488, 6397.808065758035, 6145.508359331243, 6149.877002459926, 6154.599951003171, 6206.937930620727, 6228.065790131516, 6181.196444462191, 6265.65538895356, 6295.905561416583, 6296.464800491918, 6308.337624549448, 6214.316425193225, 6221.813345599639, 6259.436650701458, 6305.590136641195, 6311.431971122621, 6250.781222657276, 6267.122602835607, 6269.279032911182, 6273.309935907047, 6283.434490895841, 4113.239050775616, 4381.605655491021, 4986.227134794112, 5007.399112106497, 5038.496150384932, 4374.475062992397, 4422.800973956179, 4448.866534003985, 4463.4420697412825, 4505.121501307279, 4700.271457924027, 5865.35481097079, 5943.5839491236065, 6029.0, 6049.334573196945, 6251.3123556408755, 6288.591241569284, 6295.748170073238, 6322.304546499881, 6385.297617262097, 5938.435848594377, 5973.880522389528, 5976.5218782497295, 6020.608660437058, 6074.149619053336, 5431.321084864392, 5516.089845175581, 5520.171974203901, 5526.281583394133, 5533.3083364579425, 6326.620402775849, 6370.8088757337355, 6394.9680251599375, 6411.038344248307, 6452.935470645421, 6283.5601507895835, 6283.842903927255, 6284.968575157095, 6294.496440284858, 6315.5894866833505, 6258.0925765762795, 6457.063725759833, 6485.3514648534765, 6487.935120648733, 6528.543001989815, 6013.218281623361, 6131.682521312785, 6141.263048434216, 6177.938220617736, 6224.813255602339, 5506.531944784578, 6231.813045608639, 6241.0, 6242.219722534684, 6262.123302737711, 6132.264128304632, 6151.477124444473, 6159.076138579249, 6167.84580385494, 6179.196704428396, 5219.4780521947505, 6143.600665956698, 6257.655828929453, 6277.843053923689, 6286.874262514632, 6033.456988871023, 6086.7260973255425, 6111.602745821508, 6131.356207598138, 6148.907766383597, 6144.262688477233, 6178.227721534809, 6187.474064704551, 6232.563720539519, 6246.406591373812, 6084.404291473306, 6147.016477363601, 6156.722947467285, 6166.445019948226, 6167.969160154171], "value": 5706.706028233044, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [491.33424210195176, 520.4067363205944, 521.5306224398043, 524.4414698346261, 559.4936582392936, 36611.81694091529, 38896.80551597242, 39008.99999999996, 39011.609883901154, 39122.60877391225, 37649.0, 39285.41071883924, 39969.400458993136, 40114.59885401145, 40122.39816402756, 9332.766680833, 9507.47708876015, 9507.857382139273, 9512.952435237814, 9516.80966380672, 9412.670556530522, 9461.290403219786, 9475.857862132074, 9486.905130948706, 9508.667196648119, 9495.905040949605, 9535.856962145539, 9543.284253681002, 9547.904520954806, 9555.044495550459, 9454.952725236364, 9513.952430237805, 9526.618935242583, 9529.809403811954, 9543.761405964837, 9437.905620943771, 9455.952720236424, 9476.147146756824, 9497.050294970448, 9508.049195080506, 37843.24313513714, 38231.61768382308, 38318.80840595779, 38346.80826595877, 38870.22259554821, 3624.1845584743223, 3704.907377315562, 3732.981335093334, 3810.580836108026, 4076.408920706511, 30227.546586801214, 31015.534766978515, 31352.529712054336, 31364.902228422125, 31680.89116880898, 3170.359809105121, 3300.7359411247285, 3346.498025296201, 3360.7479439041927, 3362.815045172539, 311358.0, 318574.4071279652, 363607.0, 370252.0, 372750.0, 27073.458530829274, 34327.0, 34412.82793586066, 58971.41028589745, 59072.7046364774, 34888.651113489046, 34977.65022349795, 35217.295654086774, 35423.0, 35452.468212976826, 14746.188959607325, 14935.925320373546, 14958.62603434901, 14984.925075374553, 14996.725278351245, 12678.936605316914, 12802.487900484062, 12816.974642028616, 12838.716128387274, 12839.935800320938, 7634.4655874088285, 7654.540727556365, 7667.769966901002, 7693.692252309845, 7709.0749110107145, 2788.776897848167, 2808.719128087175, 3236.093893709765, 3267.803931764102, 3299.554560134351, 935.9064093590723, 945.4847108325979, 987.0524296675129, 990.8018396320765, 1050.4747626186906, 28390.574141387897, 29900.0, 30171.849140754595, 30795.0, 31586.684133158833, 961.927855410847, 1020.2654089055868, 1110.6334909479851, 1113.8997490225918, 1178.3369584738552, 13663.931680341733, 15408.383664653513, 15590.9220453897, 15610.375584976473, 15982.680346393006, 593290.0, 653210.0, 671172.0, 672599.0, 674977.0, 57175.0, 57508.0, 57955.0, 57980.420195798346, 58080.709596452594, 1373.7321222361743, 1381.647679841634, 1428.8999770015998, 1430.2276770543997, 1436.2172615924137, 25154.61031202033, 29831.552526712552, 30015.849920750257, 30038.54942175913, 30186.24534386658, 51229.231561527355, 51814.48185518247, 51840.222396663325, 52536.0, 52611.0, 621244.0, 645186.0, 646944.0, 647196.0, 650210.0, 26284.080057197905, 26347.604785927844, 26621.0, 26658.466830663667, 26684.332891677863, 46859.53140468688, 46918.76540617275, 47110.0, 47824.0, 48387.27419088787, 38354.0, 38848.0, 38906.416403754534, 39179.41230881482, 39683.0, 38798.0, 39203.019924502114, 39489.81530554088, 39600.0, 40618.98452538592, 38135.42796857994, 38501.6149838498, 39148.217035658556, 39304.410433842946, 41252.58747412488, 26784.59823102616, 29189.562156567245, 32041.67958320387, 34718.37947118255, 35571.0, 26121.346966325993, 26243.212703618923, 26319.0, 26607.20178394651, 26750.197494075208], "value": 18408.72680870489, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [378.42290506976855, 383.24883228871414, 384.24495865624044, 389.7583498231097, 403.70932928291637, 18509.981950992707, 20389.0, 20475.07862146203, 20523.897380513095, 20533.691994620094, 19981.0, 20015.999200040013, 20796.376108716726, 20891.37325880222, 20988.16047358104, 5264.684118952875, 5765.7981970631035, 5919.940800592003, 5928.2293301870695, 5941.04578275013, 5868.559858010645, 5875.7062146892695, 5918.171455996164, 5924.763009479637, 5929.73316200772, 5402.621816472849, 5769.1923130761725, 5770.653760774367, 5854.590178687494, 5878.118282257674, 5678.375378708339, 5793.768249270024, 5816.4474374934525, 5827.35899051104, 5846.619969701977, 4723.881902952454, 5065.8733531662, 5067.417247016603, 5070.974645126787, 5084.923726144111, 23190.3042908713, 24333.756662433327, 24570.385740356454, 24671.62992555113, 24856.87571562149, 2440.707115146176, 2471.1721573272976, 2840.3751174741524, 3001.189678786737, 3075.138961090898, 15024.774628380583, 15189.848101518954, 15192.924035379863, 15311.31099100532, 15620.140892250922, 1745.0751101916005, 1811.3799973172086, 1835.4677143628332, 1934.2553117049897, 1958.8922609256485, 98850.50574747153, 101104.49447752787, 101926.49036754842, 102495.46256806079, 102781.45827812514, 9150.35947483683, 11238.943805280922, 11432.0, 11830.408479575986, 11893.464794084288, 14490.203038832966, 14651.0, 14799.482018129314, 14875.404983800527, 14944.402223910922, 7138.286171382924, 8561.272291855137, 8801.779955501164, 8878.600462979182, 8967.237784788365, 7245.275472452712, 8833.558322083998, 8834.734957951272, 8905.777355566162, 9011.729648110568, 4570.908581828345, 4616.0, 4650.4186976627925, 4711.363965864564, 4738.64460165489, 1679.5213364191147, 1689.4678176374648, 1953.540917884296, 1982.0, 2001.2895422125227, 716.7563028570311, 766.8880123820371, 867.7179916527027, 872.0625327772669, 984.4191926763101, 20764.1694332225, 21035.368938931857, 21334.35996920064, 21338.359849204237, 21493.570128597654, 837.656560810065, 946.8343039967978, 955.3551352836946, 992.8957459466797, 1000.7097941596855, 10368.792624147474, 10531.104856087166, 11115.555377784798, 11139.275947063416, 11141.108711303077, 569704.0, 594913.0, 598668.0, 613552.0, 622631.0, 131257.0, 133636.0, 133973.99039014423, 135160.0, 136606.0, 944.4333399959969, 960.7790208252142, 963.3160456076124, 963.629002833914, 976.067855198284, 18186.36347727823, 18644.533886652942, 18803.905980470012, 18924.580656451097, 19018.144183512053, 52115.73942130265, 54368.728156358964, 54438.727806360715, 54518.72740636271, 55219.72390138024, 75364.62317688376, 80971.78542321752, 81883.5905820467, 82300.17699822925, 82508.58745706233, 8083.690441150916, 8102.311303539265, 8103.8379232413845, 8265.008199016156, 8370.581470926307, 11361.886381136082, 12303.9384803079, 12635.557755478514, 12680.0, 12710.110292279453, 10755.193360497837, 10910.74526429439, 10918.72703182395, 11033.61382351646, 11035.22753407286, 9522.0, 10728.839067414152, 10817.621383251548, 10960.561577537128, 11009.119270458505, 10989.560417583207, 11088.556457741923, 11160.0, 11241.0, 11258.268212566158, 8135.75592732219, 8174.877376839233, 8195.672173113247, 8226.506409615204, 8313.168683131638, 9438.716838494856, 10055.597776088875, 10667.786644267226, 10996.615118470816, 11037.061849742862], "value": 9605.992458525743, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1559.5945054285885, 1604.1417841454822, 1836.2471386731443, 1927.1135277772216, 1929.1608150454563, 7468.365188958938, 7862.567558784264, 8442.535660538668, 8919.15268049536, 8922.91077089229, 8553.871691924625, 8638.956805215965, 8705.085965973576, 8731.432456890298, 8732.432391894537, 2159.7408311002623, 2219.4340443186966, 2350.999999999996, 2371.312319427364, 2376.4415362389823, 2173.3371321746863, 2198.395441253652, 2388.399772152653, 2391.7727815857465, 2396.6405039244078, 2192.8597129492655, 2227.1425501182, 2337.1819863047967, 2355.9764402356013, 2374.27584586701, 2331.591971405014, 2338.3101984914406, 2345.0971376020184, 2353.1999120299097, 2358.917437889674, 1953.384683824605, 1955.8337541309006, 2333.556624241389, 2335.7781010803947, 2340.8946597403155, 8688.391812573122, 8825.691100811506, 8844.69043583478, 8847.823043539092, 8870.778230544289, 5126.5642420394315, 5269.552088072518, 5286.81496147633, 5290.100682883919, 5292.285541451894, 3165.0, 3472.8958131256104, 3565.750397472178, 3628.2924829658227, 3654.5979942206336, 1088.8965548272981, 1090.2695194219891, 1093.1200383691125, 1098.6099934523288, 1116.4975760907623, 9629.614815407374, 11355.432228388547, 11484.483198256099, 11493.712657183554, 11538.480768365444, 4236.046889449849, 4581.106684196549, 4627.884302892455, 4778.211595086841, 4816.253480710496, 9276.11876871696, 9749.756256093513, 9756.90243097574, 9767.902320976842, 9783.217342612574, 5314.3091398117995, 5320.015797077553, 5332.280142180756, 5350.545203657655, 5361.410244873061, 4372.125574885035, 4463.7991290391365, 4888.926666100011, 4904.852854414302, 4914.262860570871, 3923.803809809498, 3939.803009849496, 3998.2403343364667, 4018.6985976051915, 4082.3264161413626, 1288.7809072457517, 1289.5873320537526, 1290.9160904541175, 1297.1438850358718, 1306.7190554030929, 911.7264820553808, 935.4153653966271, 938.2822141062093, 943.8867335919733, 955.4553904274496, 5151.665141765773, 5868.911966320508, 5958.463738263577, 6059.36376680451, 6096.451319381277, 417.8433087592153, 437.7351702220175, 438.3709377043952, 448.3745175480187, 457.0972329648917, 4048.08917993455, 4072.898177545526, 4089.9161722143654, 4112.547619761824, 4114.321137012359, 10271.126954208976, 10648.14814814813, 10649.89350106489, 10657.413842238751, 10661.147108231324, 10483.895161048444, 10556.944305569381, 10645.095166910745, 10690.037896589345, 10740.30188037775, 8149.388795840336, 8246.83506329882, 8248.711295104642, 8250.628721707537, 8262.339012878956, 5635.6618602883955, 5776.451237132465, 5850.23946886902, 5903.793367232126, 5920.644761314335, 9832.60669573209, 9835.803283934425, 9863.260255480724, 9866.851997219905, 9921.65274215399, 8177.918220817715, 9764.85352719724, 10052.949735251277, 10206.0, 10217.182625389802, 6049.0, 6864.0, 7434.962825185839, 7449.6275186241555, 7487.2138425464545, 8428.578571071299, 8436.789080273042, 8500.192481714228, 8503.74488765338, 8520.275776559061, 6170.136180934763, 6891.724331026703, 7263.927360726325, 7408.851822963618, 7661.080670319598, 5841.737121829443, 6047.969760151171, 6199.907001395073, 7468.738594149396, 7514.774556763306, 6983.336583024605, 6989.056477375692, 7039.979203015431, 7057.823554411181, 7094.432445404458, 4963.751812409438, 5002.974985125051, 5010.49895010496, 5127.333446651912, 5163.173892177234, 4410.250257456304, 5027.924581131213, 5041.193409054534, 5080.161773307363, 5104.464031276662], "value": 4454.00929776933, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [305.9066984569706, 310.45205212799414, 312.4001916320664, 313.2903972502281, 316.0959655385598, 4512.9548704512945, 4655.30170474429, 5601.6358936669085, 5673.886522269552, 5674.716264186794, 4573.954260457394, 4850.490698476662, 5496.038193316163, 5528.115501519757, 5584.888302233953, 3215.9839200803963, 4007.559168491478, 4109.609587089221, 4145.834166633331, 4206.516250631186, 3750.643688849555, 3793.525809273841, 4190.099128687331, 4216.704830661855, 4222.197782421345, 4156.044109854735, 4201.3697945308295, 4203.558626344236, 4204.453421055258, 4231.51896836108, 3792.317382871096, 4124.566920473337, 4186.58134186582, 4199.0, 4248.4477017987465, 3592.9281414371826, 3603.891883243507, 3621.7645853019735, 3644.69020133289, 3655.670989610948, 5432.984693495108, 5447.400785913546, 5448.83653490396, 5485.917711234335, 5519.60544265278, 4711.290416432268, 4771.403574553181, 4875.902481950341, 4887.926681099786, 4890.193118135503, 4914.68054576455, 5639.407862174454, 5714.571407144439, 5867.501262392701, 5903.793367232126, 3347.0126312737557, 4029.879103626896, 4047.150098479323, 4061.715679902408, 4077.2049450357194, 5110.616703747197, 5416.850989378054, 5819.854503637401, 6175.475084617813, 6268.561200715906, 3672.540932383452, 4063.4108054331955, 4067.7966101694797, 4075.7962101894786, 4104.486939132608, 3447.0696410009973, 3875.980620096938, 3933.1347103637154, 3972.6226008529143, 4165.58344165581, 3806.0779306450067, 3961.782101984361, 4047.5547689754103, 4867.221244600847, 4876.707397556158, 4549.931751023737, 4812.542808433193, 4914.92627610579, 5967.492763115184, 5971.775785963867, 1097.665212110314, 1394.4979807269374, 1394.7350003499303, 1397.9720405591831, 1399.783033629789, 840.3403328387309, 844.907060223375, 849.6261644876234, 853.9573021349032, 890.6214858685033, 5415.106507426231, 5462.432656651367, 5468.043092458804, 5474.507294343449, 5525.530329921921, 5779.0, 5852.531797456193, 5930.199423077829, 5937.7921772737745, 5961.406788047213, 4397.273690311963, 4532.818687252473, 5068.746562671851, 5433.864153396196, 5489.7648029193115, 2912.3788590268928, 3144.9371012579622, 3154.9684503154676, 3163.3831402876676, 3165.382750363657, 5380.1929710542945, 5953.1367951647635, 6068.120122582201, 6179.134921111048, 6187.84530386744, 5585.972070139705, 5875.823725288248, 6026.758929642853, 6123.938760612336, 6170.6914654268, 4934.827281045146, 6151.754609567475, 6181.567290289637, 6189.288231853395, 6260.530460215593, 5022.422421421583, 5367.329083864517, 5520.06158952971, 5574.498295153456, 5953.928292907145, 5496.615236933376, 5546.805861794818, 5662.433756624305, 5876.6180198286975, 5931.940680593311, 5748.080307151003, 5766.509846663081, 5851.239338885917, 6171.876562468815, 6177.6911154443, 3407.2333724911696, 3433.8454769534933, 3917.29597625034, 3952.7628342299554, 3960.2475529649273, 4738.431388233434, 4793.880152996203, 5482.91775623358, 5984.1921340618455, 5995.961373133156, 5409.432009639088, 5579.77680892776, 5740.0815869460685, 5802.83943211351, 5832.24180856486, 5271.262023316662, 5744.770209191753, 5760.625559338797, 5806.0, 5816.912746308724, 4669.556392142741, 4952.182889823139, 5536.335639723098, 5656.406077361982, 5660.377358490645, 5705.91734017089, 5824.271966004249, 5840.561957853263, 5878.2064421304285, 5878.794242201502, 5706.714664266687, 5712.685802280748, 5745.511631511368, 5752.367239603724, 5755.971220143872], "value": 4191.443937710856, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [745.4856149257013, 768.1511929318103, 777.5257093173167, 809.959202424884, 836.9958150209249, 15111.622209444755, 15684.451044213454, 15849.762253566205, 15861.524154275361, 15868.920655396721, 13739.175649461014, 14543.418263269483, 14603.342849571769, 14624.926875365609, 14628.34172462239, 871.3247233394122, 882.2677177942293, 883.6244596046687, 885.3758100539137, 887.4453466583385, 653.460894761821, 675.9155105611799, 891.0599317719802, 898.8606765951255, 899.3479727197762, 935.0415823780611, 951.4529145741192, 953.0993211415181, 953.3088510829663, 957.1290125985355, 941.8398872191709, 945.394947233764, 946.3659348236698, 946.8011717539325, 949.5632009275707, 678.1049015299807, 690.823839920817, 702.2240424331143, 706.3713295167305, 709.5387997801419, 15069.09585424878, 15148.0, 15148.772768408482, 15172.620684482867, 15179.013364131297, 12131.514739410413, 14641.780373294408, 14745.631359216106, 14752.262386880613, 14897.47858824936, 11931.642050738492, 12194.268343899306, 12417.503299867994, 12446.377681115908, 12497.250164990039, 3808.809559522013, 3811.923761524754, 4003.719739618228, 4021.8592349267633, 4026.0, 76901.23098768997, 106065.0, 106381.46809265981, 106617.40073898897, 107540.0, 10951.34291942486, 11039.282446640942, 11347.432628368548, 11454.770904581861, 11579.305241685526, 22696.546069078857, 22951.655725164135, 23144.0, 23218.3034508965, 23361.766382335958, 6783.660816959133, 8124.512529248264, 8130.30892374143, 8199.918000820035, 8273.379496537784, 4453.55464453552, 4544.409226800495, 4561.954380456153, 4633.258678611406, 4655.557722016403, 3401.9319613608086, 3474.5656792900886, 3502.1594817243677, 3511.94732079019, 3514.947275790865, 635.7584118035116, 640.6444423344996, 650.0574167457206, 654.3293124547282, 660.4320284555279, 597.5966222799593, 605.857623458487, 638.2277444292365, 658.4633523678187, 669.531328070343, 9770.609175632895, 9770.853437198448, 9771.019838949189, 9771.609135634637, 9889.703308900744, 576.5358886096733, 583.3845293215699, 694.3681250062419, 696.3036963036963, 699.5627732667083, 3753.3619284721663, 4617.261238201939, 4721.291806229025, 4721.575058244705, 4722.504137065627, 121072.39463802625, 144844.0, 146597.0, 147898.0, 149095.0, 114615.42692286485, 117539.0, 126323.0, 128686.13941581767, 130323.0, 110956.0, 112796.43601782103, 113837.0, 114713.0, 115854.0, 9110.316726245426, 9150.313726470407, 9197.86203206939, 9212.401193922373, 9251.583678734607, 48806.267905982146, 56259.718701407866, 61561.69219154055, 63022.684886575276, 63256.36743632505, 81087.78368324597, 81618.0, 82619.0, 89150.10849891677, 89428.6585701228, 13165.341732913508, 13836.377363018488, 13897.166170029426, 13992.510262140777, 14219.50231741884, 13510.932445338103, 14262.800493681725, 14915.477958271842, 15175.165365904539, 15373.615659608598, 12151.75696486083, 13918.860811392162, 13965.0, 14232.288385580887, 14362.13827170373, 13802.723945521235, 13995.790063148857, 14616.415343386145, 14631.048981816148, 14695.338709757872, 13927.442902283796, 14049.438022478986, 14074.929625351808, 14117.08238964464, 14575.854241457873, 11458.713532161762, 11459.828102578635, 11552.537898483966, 11639.709007274885, 11926.821097683369, 10295.661563996931, 10970.561177553127, 11748.353840538852, 11774.234674746456, 11774.705632359259], "value": 7911.888576916848, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1568.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 116.0, 107.0, 116.0, 115.0, 116.0, 114.0, 115.0, 115.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 127.0, 146.0, 152.0, 110.0, 131.0, 131.0, 110.0, 155.0, 109.0], "value": 131.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [200.0, 222.0, 195.0, 209.0, 279.0, 277.0, 199.0, 209.0, 231.0, 199.0], "value": 222.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 86.0, 77.0, 155.0, 88.0, 84.0, 68.0, 58.0, 132.0, 61.0], "value": 87.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 121.0, 72.0, 71.0, 85.0, 113.0, 71.0, 73.0, 110.0], "value": 85.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 83.0, 77.0, 133.0, 136.0, 94.0, 91.0, 89.0, 95.0, 83.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 118.0, 95.0, 99.0, 98.0, 97.0, 96.0, 98.0, 96.0, 97.0], "value": 99.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 146.0, 79.0, 106.0, 142.0, 150.0, 82.0, 143.0, 146.0, 91.0], "value": 116.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 55.0, 57.0, 72.0, 69.0, 58.0, 58.0, 55.0, 57.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 73.0, 69.0, 78.0, 79.0, 84.0, 75.0, 70.0, 70.0, 73.0], "value": 73.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 102.0, 89.0, 83.0, 85.0, 83.0, 86.0, 85.0, 97.0, 111.0], "value": 91.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 126.0, 151.0, 149.0, 137.0, 145.0, 151.0, 140.0, 179.0, 175.0], "value": 149.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 165.0, 162.0, 163.0, 166.0, 162.0, 163.0, 165.0, 166.0, 162.0], "value": 163.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 86.0, 85.0, 85.0, 86.0, 84.0, 71.0, 69.0, 67.0, 68.0], "value": 76.0, "unit": "score"}]}]} 11:22:36 INFO - Return code: 0 11:22:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:22:36 ERROR - # TBPL SUCCESS # 11:22:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:22:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:22:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:22:36 INFO - 2016-07-09 11:22:36.532 screenresolution[2046:27389] starting screenresolution argv=screenresolution get 11:22:36 INFO - 2016-07-09 11:22:36.534 screenresolution[2046:27389] Display 0: 1600x1200x32@60 11:22:36 INFO - 2016-07-09 11:22:36.540 screenresolution[2047:27392] starting screenresolution argv=screenresolution list 11:22:36 INFO - Available Modes on Display 0 11:22:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:22:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:22:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:22:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:22:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:22:36 INFO - Intel Iris: 11:22:36 INFO - Chipset Model: Intel Iris 11:22:36 INFO - Type: GPU 11:22:36 INFO - Bus: Built-In 11:22:36 INFO - VRAM (Dynamic, Max): 1536 MB 11:22:36 INFO - Vendor: Intel (0x8086) 11:22:36 INFO - Device ID: 0x0a2e 11:22:36 INFO - Revision ID: 0x0009 11:22:36 INFO - Displays: 11:22:36 INFO - Display: 11:22:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:22:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:22:36 INFO - Display Serial Number: 1600x1200 60 11:22:36 INFO - Main Display: Yes 11:22:36 INFO - Mirror: Off 11:22:36 INFO - Online: Yes 11:22:36 INFO - Rotation: Supported 11:22:36 INFO - Return code: 0 11:22:36 INFO - Running post-action listener: _resource_record_post_action 11:22:36 INFO - ##### 11:22:36 INFO - ##### Finished run-tests step (success) 11:22:36 INFO - ##### 11:22:36 INFO - Running post-run listener: _resource_record_post_run 11:22:36 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 430080; Write bytes: 708253696; Read time: 5; Write time: 2081 11:22:36 INFO - TinderboxPrint: CPU usage
54.1% 11:22:36 INFO - TinderboxPrint: I/O read bytes / time
430,080 / 5 11:22:36 INFO - TinderboxPrint: I/O write bytes / time
708,253,696 / 2,081 11:22:36 INFO - TinderboxPrint: CPU idle
2,218.5 (45.9%) 11:22:36 INFO - TinderboxPrint: CPU system
977.0 (20.2%) 11:22:36 INFO - TinderboxPrint: CPU user
1,642.1 (33.9%) 11:22:36 INFO - TinderboxPrint: Swap in / out
405,954,560 / 0 11:22:36 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 404066816; Write bytes: 399397888; Read time: 31081; Write time: 775 11:22:36 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 356352; Write bytes: 297219072; Read time: 4; Write time: 1271 11:22:37 INFO - Running post-run listener: _upload_blobber_files 11:22:37 INFO - Blob upload gear active. 11:22:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:22:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 11:22:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:22:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:22:37 INFO - (blobuploader) - INFO - Open directory for files ... 11:22:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 11:22:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 11:22:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:38 INFO - (blobuploader) - INFO - Done attempting. 11:22:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 11:22:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 11:22:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:39 INFO - (blobuploader) - INFO - Done attempting. 11:22:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:22:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:22:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:22:42 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:22:42 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:22:42 INFO - (blobuploader) - INFO - Done attempting. 11:22:42 INFO - (blobuploader) - INFO - Iteration through files over. 11:22:42 INFO - Return code: 0 11:22:42 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:22:42 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:22:42 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93492f7b5db9f94a1b1c30b7d6e4377fa9e5a438181f721e00ffdb3606297d5f67700457f7a48b601689aa6b60035f78849dff5dc4057fa315237be77d671894", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b2e1dd48a788d3431393e6dab94326ec7934cbce32eb2217bb65dd95fa686296773b024a7c9133e13f3213c71ab3fe6a11e50e34f220bc2d834bf2925cb3e771", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8a6b691e87ae7da701cec96da1140b46cd54fe6f2b90986bceca6067da8c626dbc9452bd1e11eff95e0005d2c98a17dae5613979f630e82774e5be53bea0a3d6"} 11:22:42 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:22:42 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:22:42 INFO - Contents: 11:22:42 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93492f7b5db9f94a1b1c30b7d6e4377fa9e5a438181f721e00ffdb3606297d5f67700457f7a48b601689aa6b60035f78849dff5dc4057fa315237be77d671894", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b2e1dd48a788d3431393e6dab94326ec7934cbce32eb2217bb65dd95fa686296773b024a7c9133e13f3213c71ab3fe6a11e50e34f220bc2d834bf2925cb3e771", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8a6b691e87ae7da701cec96da1140b46cd54fe6f2b90986bceca6067da8c626dbc9452bd1e11eff95e0005d2c98a17dae5613979f630e82774e5be53bea0a3d6"} 11:22:42 INFO - Running post-run listener: copy_logs_to_upload_dir 11:22:42 INFO - Copying logs to upload dir... 11:22:42 INFO - mkdir: /builds/slave/test/build/upload/logs 11:22:42 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1323.782920 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 3 secs) (at 2016-07-09 11:22:42.184125) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 11:22:42.189164) ========= 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.210XvjmTW2/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.j7XcVKQD7a/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93492f7b5db9f94a1b1c30b7d6e4377fa9e5a438181f721e00ffdb3606297d5f67700457f7a48b601689aa6b60035f78849dff5dc4057fa315237be77d671894", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b2e1dd48a788d3431393e6dab94326ec7934cbce32eb2217bb65dd95fa686296773b024a7c9133e13f3213c71ab3fe6a11e50e34f220bc2d834bf2925cb3e771", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8a6b691e87ae7da701cec96da1140b46cd54fe6f2b90986bceca6067da8c626dbc9452bd1e11eff95e0005d2c98a17dae5613979f630e82774e5be53bea0a3d6"} build_url:https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011629 build_url: 'https://queue.taskcluster.net/v1/task/VizePUSFT72WKJhsZT0Law/artifacts/public/build/firefox-50.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/93492f7b5db9f94a1b1c30b7d6e4377fa9e5a438181f721e00ffdb3606297d5f67700457f7a48b601689aa6b60035f78849dff5dc4057fa315237be77d671894", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/b2e1dd48a788d3431393e6dab94326ec7934cbce32eb2217bb65dd95fa686296773b024a7c9133e13f3213c71ab3fe6a11e50e34f220bc2d834bf2925cb3e771", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/8a6b691e87ae7da701cec96da1140b46cd54fe6f2b90986bceca6067da8c626dbc9452bd1e11eff95e0005d2c98a17dae5613979f630e82774e5be53bea0a3d6"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-07-09 11:22:42.223106) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:22:42.223458) ========= 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.210XvjmTW2/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.j7XcVKQD7a/Listeners TMPDIR=/var/folders/qg/7pj314vs6ns665sg_7g6q8x000000w/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.005417 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-07-09 11:22:42.272842) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 11:22:42.273219) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-07-09 11:22:42.273568) ========= ========= Total master_lag: 0.27 =========