builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0250 starttime: 1491771159.38 results: success (0) buildid: 20170409123541 builduid: 4dd7c17bbc4047db9fd236903f84a75c revision: 45692c884fdd ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.381533) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.381935) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.382245) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.410064) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.410431) ========= 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.X0MI16m5TB/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.rl6XGodboW/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 --2017-04-09 13:52:39-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 15.2M=0.001s 2017-04-09 13:52:39 (15.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.151147 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.586092) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.586423) ========= 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.X0MI16m5TB/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.rl6XGodboW/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.027002 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.636559) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:39.636926) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 45692c884fdd --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 45692c884fdd --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X0MI16m5TB/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.rl6XGodboW/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 2017-04-09 13:52:39,744 Setting DEBUG logging. 2017-04-09 13:52:39,744 attempt 1/10 2017-04-09 13:52:39,744 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/45692c884fdd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-09 13:52:40,409 unpacking tar archive at: mozilla-central-45692c884fdd/testing/mozharness/ program finished with exit code 0 elapsedTime=0.949104 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:40.610720) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:40.611167) ========= script_repo_revision: 45692c884fdd ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:40.611827) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:40.612343) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-09 13:52:40.633497) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-09 13:52:40.633962) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.X0MI16m5TB/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.rl6XGodboW/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:52:40 INFO - MultiFileLogger online at 20170409 13:52:40 in /builds/slave/test 13:52:40 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:52:40 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:52:40 INFO - {'append_to_log': False, 13:52:40 INFO - 'base_work_dir': '/builds/slave/test', 13:52:40 INFO - 'blob_upload_branch': 'Firefox', 13:52:40 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:52:40 INFO - 'branch': 'Firefox', 13:52:40 INFO - 'buildbot_json_path': 'buildprops.json', 13:52:40 INFO - 'config_files': ('talos/mac_config.py',), 13:52:40 INFO - 'default_actions': ('clobber', 13:52:40 INFO - 'read-buildbot-config', 13:52:40 INFO - 'download-and-extract', 13:52:40 INFO - 'populate-webroot', 13:52:40 INFO - 'create-virtualenv', 13:52:40 INFO - 'install', 13:52:40 INFO - 'run-tests'), 13:52:40 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:52:40 INFO - 'download_minidump_stackwalk': True, 13:52:40 INFO - 'download_symbols': 'ondemand', 13:52:40 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:52:40 INFO - 'tooltool.py': '/tools/tooltool.py', 13:52:40 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:52:40 INFO - '/tools/misc-python/virtualenv.py')}, 13:52:40 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:52:40 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:52:40 INFO - 'gecko_profile': False, 13:52:40 INFO - 'gecko_profile_interval': 0, 13:52:40 INFO - 'installer_path': 'installer.exe', 13:52:40 INFO - 'log_level': 'info', 13:52:40 INFO - 'log_name': 'talos', 13:52:40 INFO - 'log_to_console': True, 13:52:40 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:52:40 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:52:40 INFO - 'opt_config_files': (), 13:52:40 INFO - 'pip_index': False, 13:52:40 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:52:40 INFO - 'cmd': ('bash', 13:52:40 INFO - '-c', 13:52:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:52:40 INFO - 'enabled': True, 13:52:40 INFO - 'halt_on_failure': False, 13:52:40 INFO - 'name': 'check_screen_resolution'},), 13:52:40 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:52:40 INFO - 'cmd': ('bash', 13:52:40 INFO - '-c', 13:52:40 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:52:40 INFO - 'enabled': True, 13:52:40 INFO - 'halt_on_failure': False, 13:52:40 INFO - 'name': 'check_screen_resolution'},), 13:52:40 INFO - 'run_cmd_checks_enabled': True, 13:52:40 INFO - 'suite': 'dromaeojs-e10s', 13:52:40 INFO - 'system_bits': '32', 13:52:40 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:52:40 INFO - 'title': 't-yosemite-r7-0250', 13:52:40 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:52:40 INFO - 'use_talos_json': True, 13:52:40 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:52:40 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:52:40 INFO - 'work_dir': 'build'} 13:52:40 INFO - [mozharness: 2017-04-09 20:52:40.845448Z] Running clobber step. 13:52:40 INFO - Running pre-action listener: _resource_record_pre_action 13:52:40 INFO - Running main action method: clobber 13:52:40 INFO - rmtree: /builds/slave/test/build 13:52:40 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:52:45 INFO - Running post-action listener: _resource_record_post_action 13:52:45 INFO - [mozharness: 2017-04-09 20:52:45.068112Z] Finished clobber step (success) 13:52:45 INFO - [mozharness: 2017-04-09 20:52:45.068309Z] Running read-buildbot-config step. 13:52:45 INFO - Running pre-action listener: _resource_record_pre_action 13:52:45 INFO - Running main action method: read_buildbot_config 13:52:45 INFO - Using buildbot properties: 13:52:45 INFO - { 13:52:45 INFO - "project": "", 13:52:45 INFO - "product": "firefox", 13:52:45 INFO - "script_repo_revision": "production", 13:52:45 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 13:52:45 INFO - "repository": "", 13:52:45 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 13:52:45 INFO - "buildid": "20170409123541", 13:52:45 INFO - "stage_platform": "macosx64", 13:52:45 INFO - "basedir": "/builds/slave/test", 13:52:45 INFO - "nightly_build": "True", 13:52:45 INFO - "buildnumber": 8, 13:52:45 INFO - "slavename": "t-yosemite-r7-0250", 13:52:45 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 13:52:45 INFO - "platform": "yosemite_r7", 13:52:45 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 13:52:45 INFO - "branch": "mozilla-central", 13:52:45 INFO - "repo_path": "mozilla-central", 13:52:45 INFO - "revision": "45692c884fdd", 13:52:45 INFO - "pgo_build": "False", 13:52:45 INFO - "builduid": "4dd7c17bbc4047db9fd236903f84a75c", 13:52:45 INFO - "slavebuilddir": "test" 13:52:45 INFO - } 13:52:45 INFO - Found installer url https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 13:52:45 INFO - Running post-action listener: _resource_record_post_action 13:52:45 INFO - [mozharness: 2017-04-09 20:52:45.073996Z] Finished read-buildbot-config step (success) 13:52:45 INFO - [mozharness: 2017-04-09 20:52:45.074171Z] Running download-and-extract step. 13:52:45 INFO - Running pre-action listener: _resource_record_pre_action 13:52:45 INFO - Running main action method: download_and_extract 13:52:45 INFO - mkdir: /builds/slave/test/build/tests 13:52:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:52:45 INFO - trying https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 13:52:45 INFO - Downloading https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:52:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 13:52:46 INFO - Downloaded 1325 bytes. 13:52:46 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 13:52:46 INFO - Using the following test package requirements: 13:52:46 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 13:52:46 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:52:46 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 13:52:46 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 13:52:46 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'jsshell-mac.zip'], 13:52:46 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 13:52:46 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 13:52:46 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 13:52:46 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 13:52:46 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 13:52:46 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 13:52:46 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 13:52:46 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:52:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:52:46 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:52:46 INFO - Fetch https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:52:47 INFO - Expected file size: 52525250 13:52:47 INFO - Obtained file size: 52525250 13:53:04 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 13:53:04 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 13:53:04 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 13:53:04 INFO - Fetch https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 13:53:06 INFO - Expected file size: 52525250 13:53:06 INFO - Obtained file size: 52525250 13:53:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 13:53:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 13:53:21 INFO - Fetch https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 13:53:22 INFO - Expected file size: 14404129 13:53:22 INFO - Obtained file size: 14404129 13:53:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:23 INFO - trying https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:53:23 INFO - Downloading https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 13:53:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 13:53:25 INFO - Downloaded 70994114 bytes. 13:53:25 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:53:25 INFO - mkdir: /builds/slave/test/properties 13:53:25 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:53:25 INFO - Writing to file /builds/slave/test/properties/build_url 13:53:25 INFO - Contents: 13:53:25 INFO - build_url:https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 13:53:26 INFO - Running post-action listener: _resource_record_post_action 13:53:26 INFO - Running post-action listener: set_extra_try_arguments 13:53:26 INFO - [mozharness: 2017-04-09 20:53:26.240982Z] Finished download-and-extract step (success) 13:53:26 INFO - [mozharness: 2017-04-09 20:53:26.241100Z] Running populate-webroot step. 13:53:26 INFO - Running pre-action listener: _resource_record_pre_action 13:53:26 INFO - Running main action method: populate_webroot 13:53:26 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:53:26 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:53:26 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:53:26 INFO - '--tppagecycles', 13:53:26 INFO - '7'], 13:53:26 INFO - 'tests': ['tsvgm']}}, 13:53:26 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:53:26 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:53:26 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:53:26 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:53:26 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:53:26 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:53:26 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['damp', 'tps']}, 13:53:26 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'tests': ['damp', 'tps']}, 13:53:26 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['dromaeo_dom']}, 13:53:26 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:53:26 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['basic_compositor_video']}, 13:53:26 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:53:26 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['a11yr', 13:53:26 INFO - 'ts_paint', 13:53:26 INFO - 'tpaint', 13:53:26 INFO - 'sessionrestore', 13:53:26 INFO - 'sessionrestore_no_auto_restore', 13:53:26 INFO - 'tabpaint']}, 13:53:26 INFO - 'other-e10s': {'tests': ['a11yr', 13:53:26 INFO - 'ts_paint', 13:53:26 INFO - 'tpaint', 13:53:26 INFO - 'sessionrestore', 13:53:26 INFO - 'sessionrestore_no_auto_restore', 13:53:26 INFO - 'tabpaint']}, 13:53:26 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:53:26 INFO - 'ts_paint', 13:53:26 INFO - 'tpaint', 13:53:26 INFO - 'sessionrestore', 13:53:26 INFO - 'sessionrestore_no_auto_restore', 13:53:26 INFO - 'tabpaint']}, 13:53:26 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:53:26 INFO - 'ts_paint', 13:53:26 INFO - 'tpaint', 13:53:26 INFO - 'sessionrestore', 13:53:26 INFO - 'sessionrestore_no_auto_restore', 13:53:26 INFO - 'tabpaint']}, 13:53:26 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['a11yr', 13:53:26 INFO - 'ts_paint', 13:53:26 INFO - 'tpaint', 13:53:26 INFO - 'sessionrestore', 13:53:26 INFO - 'sessionrestore_no_auto_restore', 13:53:26 INFO - 'tabpaint']}, 13:53:26 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['a11yr', 13:53:26 INFO - 'ts_paint', 13:53:26 INFO - 'tpaint', 13:53:26 INFO - 'sessionrestore', 13:53:26 INFO - 'sessionrestore_no_auto_restore', 13:53:26 INFO - 'tabpaint']}, 13:53:26 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['tsvgx', 13:53:26 INFO - 'tsvgr_opacity', 13:53:26 INFO - 'tart', 13:53:26 INFO - 'tscrollx', 13:53:26 INFO - 'cart', 13:53:26 INFO - 'tsvg_static']}, 13:53:26 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:53:26 INFO - 'tsvgr_opacity', 13:53:26 INFO - 'tart', 13:53:26 INFO - 'tscrollx', 13:53:26 INFO - 'cart', 13:53:26 INFO - 'tsvg_static']}, 13:53:26 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'talos_options': ['--disable-e10s'], 13:53:26 INFO - 'tests': ['tp5o']}, 13:53:26 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:53:26 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'talos_options': ['--disable-e10s', 13:53:26 INFO - '--xperf_path', 13:53:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:53:26 INFO - 'tests': ['tp5n']}, 13:53:26 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:53:26 INFO - 'talos_options': ['--xperf_path', 13:53:26 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:53:26 INFO - 'tests': ['tp5n']}}, 13:53:26 INFO - 'talos.zip': {'path': '', 13:53:26 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:53:26 INFO - Running post-action listener: _resource_record_post_action 13:53:26 INFO - [mozharness: 2017-04-09 20:53:26.259471Z] Finished populate-webroot step (success) 13:53:26 INFO - [mozharness: 2017-04-09 20:53:26.259587Z] Running create-virtualenv step. 13:53:26 INFO - Running pre-action listener: _resource_record_pre_action 13:53:26 INFO - Running main action method: create_virtualenv 13:53:26 INFO - Creating virtualenv /builds/slave/test/build/venv 13:53:26 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 13:53:26 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:53:26 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:53:26 INFO - Using real prefix '/tools/python27' 13:53:26 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:53:27 INFO - Installing distribute.............................................................................................................................................................................................done. 13:53:29 INFO - Installing pip.................done. 13:53:29 INFO - Return code: 0 13:53:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:53:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:53:30 INFO - Reading from file tmpfile_stdout 13:53:30 INFO - Output received: 13:53:30 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 13:53:30 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:30 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:30 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 13:53:30 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 13:53:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:30 INFO - 'HOME': '/Users/cltbld', 13:53:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:30 INFO - 'LOGNAME': 'cltbld', 13:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:30 INFO - 'MOZ_NO_REMOTE': '1', 13:53:30 INFO - 'NO_EM_RESTART': '1', 13:53:30 INFO - 'PAGER': '/bin/cat', 13:53:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:30 INFO - 'PWD': '/builds/slave/test', 13:53:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:30 INFO - 'SHELL': '/bin/bash', 13:53:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:30 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:30 INFO - 'USER': 'cltbld', 13:53:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:30 INFO - 'XPC_FLAGS': '0x0', 13:53:30 INFO - 'XPC_SERVICE_NAME': '0', 13:53:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:30 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 13:53:30 INFO - Cleaning up... 13:53:30 INFO - Return code: 0 13:53:30 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:30 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:30 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 13:53:30 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 13:53:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:30 INFO - 'HOME': '/Users/cltbld', 13:53:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:30 INFO - 'LOGNAME': 'cltbld', 13:53:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:30 INFO - 'MOZ_NO_REMOTE': '1', 13:53:30 INFO - 'NO_EM_RESTART': '1', 13:53:30 INFO - 'PAGER': '/bin/cat', 13:53:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:30 INFO - 'PWD': '/builds/slave/test', 13:53:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:30 INFO - 'SHELL': '/bin/bash', 13:53:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:30 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:30 INFO - 'USER': 'cltbld', 13:53:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:30 INFO - 'XPC_FLAGS': '0x0', 13:53:30 INFO - 'XPC_SERVICE_NAME': '0', 13:53:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:30 INFO - Downloading/unpacking psutil>=3.1.1 13:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:53:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:53:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:53:34 INFO - Installing collected packages: psutil 13:53:34 INFO - Running setup.py install for psutil 13:53:34 INFO - building 'psutil._psutil_osx' extension 13:53:34 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 13:53:35 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 13:53:35 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 13:53:35 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 13:53:35 INFO - building 'psutil._psutil_posix' extension 13:53:35 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 13:53:35 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:53:35 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:53:35 INFO - ^ 13:53:35 INFO - 1 warning generated. 13:53:35 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 13:53:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:53:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:53:35 INFO - Successfully installed psutil 13:53:35 INFO - Cleaning up... 13:53:35 INFO - Return code: 0 13:53:35 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:35 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:35 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 13:53:35 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 13:53:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:35 INFO - 'HOME': '/Users/cltbld', 13:53:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:35 INFO - 'LOGNAME': 'cltbld', 13:53:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:35 INFO - 'MOZ_NO_REMOTE': '1', 13:53:35 INFO - 'NO_EM_RESTART': '1', 13:53:35 INFO - 'PAGER': '/bin/cat', 13:53:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:35 INFO - 'PWD': '/builds/slave/test', 13:53:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:35 INFO - 'SHELL': '/bin/bash', 13:53:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:35 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:35 INFO - 'USER': 'cltbld', 13:53:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:35 INFO - 'XPC_FLAGS': '0x0', 13:53:35 INFO - 'XPC_SERVICE_NAME': '0', 13:53:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:38 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:53:38 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:53:38 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:53:38 INFO - Installing collected packages: mozsystemmonitor 13:53:38 INFO - Running setup.py install for mozsystemmonitor 13:53:39 INFO - Successfully installed mozsystemmonitor 13:53:39 INFO - Cleaning up... 13:53:39 INFO - Return code: 0 13:53:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:53:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:53:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:39 INFO - 'HOME': '/Users/cltbld', 13:53:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:39 INFO - 'LOGNAME': 'cltbld', 13:53:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:39 INFO - 'MOZ_NO_REMOTE': '1', 13:53:39 INFO - 'NO_EM_RESTART': '1', 13:53:39 INFO - 'PAGER': '/bin/cat', 13:53:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:39 INFO - 'PWD': '/builds/slave/test', 13:53:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:39 INFO - 'SHELL': '/bin/bash', 13:53:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:39 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:39 INFO - 'USER': 'cltbld', 13:53:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:39 INFO - 'XPC_FLAGS': '0x0', 13:53:39 INFO - 'XPC_SERVICE_NAME': '0', 13:53:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:39 INFO - Downloading/unpacking jsonschema==2.5.1 13:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:41 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:53:41 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:53:41 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 13:53:41 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 13:53:41 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 13:53:41 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 13:53:41 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 13:53:41 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 13:53:42 INFO - Downloading functools32-3.2.3-2.tar.gz 13:53:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:53:42 INFO - warning: no files found matching '*.txt' 13:53:42 INFO - no previously-included directories found matching 'build' 13:53:42 INFO - no previously-included directories found matching 'dist' 13:53:42 INFO - no previously-included directories found matching '.git*' 13:53:42 INFO - Installing collected packages: jsonschema, functools32 13:53:42 INFO - Running setup.py install for functools32 13:53:42 INFO - warning: no files found matching '*.txt' 13:53:42 INFO - no previously-included directories found matching 'build' 13:53:42 INFO - no previously-included directories found matching 'dist' 13:53:42 INFO - no previously-included directories found matching '.git*' 13:53:42 INFO - Successfully installed jsonschema functools32 13:53:42 INFO - Cleaning up... 13:53:42 INFO - Return code: 0 13:53:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 13:53:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 13:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:42 INFO - 'HOME': '/Users/cltbld', 13:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:42 INFO - 'LOGNAME': 'cltbld', 13:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:42 INFO - 'MOZ_NO_REMOTE': '1', 13:53:42 INFO - 'NO_EM_RESTART': '1', 13:53:42 INFO - 'PAGER': '/bin/cat', 13:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:42 INFO - 'PWD': '/builds/slave/test', 13:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:42 INFO - 'SHELL': '/bin/bash', 13:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:42 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:42 INFO - 'USER': 'cltbld', 13:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:42 INFO - 'XPC_FLAGS': '0x0', 13:53:42 INFO - 'XPC_SERVICE_NAME': '0', 13:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:42 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:53:42 INFO - Cleaning up... 13:53:42 INFO - Return code: 0 13:53:42 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 13:53:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 13:53:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:42 INFO - 'HOME': '/Users/cltbld', 13:53:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:42 INFO - 'LOGNAME': 'cltbld', 13:53:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:42 INFO - 'MOZ_NO_REMOTE': '1', 13:53:42 INFO - 'NO_EM_RESTART': '1', 13:53:42 INFO - 'PAGER': '/bin/cat', 13:53:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:42 INFO - 'PWD': '/builds/slave/test', 13:53:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:42 INFO - 'SHELL': '/bin/bash', 13:53:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:42 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:42 INFO - 'USER': 'cltbld', 13:53:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:42 INFO - 'XPC_FLAGS': '0x0', 13:53:42 INFO - 'XPC_SERVICE_NAME': '0', 13:53:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:43 INFO - Downloading/unpacking blobuploader==1.2.4 13:53:43 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 13:53:43 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 13:53:43 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 13:53:43 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 13:53:43 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 13:53:43 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 13:53:45 INFO - Downloading blobuploader-1.2.4.tar.gz 13:53:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:53:45 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:53:45 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 13:53:45 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 13:53:45 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 13:53:45 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 13:53:45 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 13:53:45 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 13:53:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:53:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:53:46 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 13:53:46 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 13:53:46 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 13:53:46 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 13:53:46 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 13:53:46 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 13:53:47 INFO - Downloading docopt-0.6.1.tar.gz 13:53:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:53:47 INFO - Installing collected packages: blobuploader, requests, docopt 13:53:47 INFO - Running setup.py install for blobuploader 13:53:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:53:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:53:47 INFO - Running setup.py install for requests 13:53:47 INFO - Running setup.py install for docopt 13:53:47 INFO - Successfully installed blobuploader requests docopt 13:53:47 INFO - Cleaning up... 13:53:48 INFO - Return code: 0 13:53:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:48 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:48 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 13:53:48 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 13:53:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:48 INFO - 'HOME': '/Users/cltbld', 13:53:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:48 INFO - 'LOGNAME': 'cltbld', 13:53:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:48 INFO - 'MOZ_NO_REMOTE': '1', 13:53:48 INFO - 'NO_EM_RESTART': '1', 13:53:48 INFO - 'PAGER': '/bin/cat', 13:53:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:48 INFO - 'PWD': '/builds/slave/test', 13:53:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:48 INFO - 'SHELL': '/bin/bash', 13:53:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:48 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:48 INFO - 'USER': 'cltbld', 13:53:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:48 INFO - 'XPC_FLAGS': '0x0', 13:53:48 INFO - 'XPC_SERVICE_NAME': '0', 13:53:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-WPzyXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-zDvgzK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ZVyozW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-me8EJd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-BwoFvS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-7vt5gw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:53:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:53:48 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-GfpPaP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-xrm1S5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-4VWRy5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-nz7wMi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-hteXAa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Z0ZJC9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-SYZrNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-uPCl0x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Xa5W4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-qKZNZ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:53:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:53:49 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-06hUBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:53:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:53:50 INFO - Running setup.py install for manifestparser 13:53:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:53:50 INFO - Running setup.py install for mozcrash 13:53:50 INFO - Running setup.py install for mozdebug 13:53:50 INFO - Running setup.py install for mozdevice 13:53:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:53:50 INFO - Running setup.py install for mozfile 13:53:50 INFO - Running setup.py install for mozhttpd 13:53:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:53:50 INFO - Running setup.py install for mozinfo 13:53:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Running setup.py install for mozInstall 13:53:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Running setup.py install for mozleak 13:53:51 INFO - Running setup.py install for mozlog 13:53:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Running setup.py install for moznetwork 13:53:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Running setup.py install for mozprocess 13:53:51 INFO - Running setup.py install for mozprofile 13:53:51 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:53:51 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:53:52 INFO - Running setup.py install for mozrunner 13:53:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:53:52 INFO - Running setup.py install for mozscreenshot 13:53:52 INFO - Running setup.py install for moztest 13:53:52 INFO - Running setup.py install for mozversion 13:53:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:53:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:53:52 INFO - Cleaning up... 13:53:52 INFO - Return code: 0 13:53:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53: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://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:52 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:52 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 13:53:52 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 13:53:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:52 INFO - 'HOME': '/Users/cltbld', 13:53:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:52 INFO - 'LOGNAME': 'cltbld', 13:53:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:52 INFO - 'MOZ_NO_REMOTE': '1', 13:53:52 INFO - 'NO_EM_RESTART': '1', 13:53:52 INFO - 'PAGER': '/bin/cat', 13:53:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:52 INFO - 'PWD': '/builds/slave/test', 13:53:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:52 INFO - 'SHELL': '/bin/bash', 13:53:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:52 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:52 INFO - 'USER': 'cltbld', 13:53:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:52 INFO - 'XPC_FLAGS': '0x0', 13:53:52 INFO - 'XPC_SERVICE_NAME': '0', 13:53:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:53:52 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-_CD3Zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-DAgx7k-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-IWvbOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-xIshXK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-KuspNM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-j1UkGT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-k0CvLg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Psg6vB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-9bG81h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:53:53 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)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-tn15IB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:53:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:53:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:53:53 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-ycv8kM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:53:54 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)) 13:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:53:54 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-Gi3ZnQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 13:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:53:54 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-AV2nAK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:53:54 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)) 13:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:53:54 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-jYvq98-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 13:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:54 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-L8fd8q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:53:54 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)) 13:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:53:54 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-vEcj6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 13:53:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:53:54 INFO - Running setup.py (path:/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/pip-dwfyHI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:53:54 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)) 13:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:53:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 13:53:54 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)) 13:53:54 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:53:54 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 13:53:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:53:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:53:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:53:57 INFO - Downloading blessings-1.6.tar.gz 13:53:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:53:57 INFO - Installing collected packages: blessings 13:53:57 INFO - Running setup.py install for blessings 13:53:57 INFO - Successfully installed blessings 13:53:57 INFO - Cleaning up... 13:53:57 INFO - Return code: 0 13:53:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:53:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:53:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:53:57 INFO - Reading from file tmpfile_stdout 13:53:57 INFO - Current package versions: 13:53:57 INFO - blessings == 1.6 13:53:57 INFO - blobuploader == 1.2.4 13:53:57 INFO - docopt == 0.6.1 13:53:57 INFO - functools32 == 3.2.3-2 13:53:57 INFO - jsonschema == 2.5.1 13:53:57 INFO - manifestparser == 1.1 13:53:57 INFO - mozInstall == 1.12 13:53:57 INFO - mozcrash == 1.0 13:53:57 INFO - mozdebug == 0.1 13:53:57 INFO - mozdevice == 0.50 13:53:57 INFO - mozfile == 1.2 13:53:57 INFO - mozhttpd == 0.7 13:53:57 INFO - mozinfo == 0.9 13:53:57 INFO - mozleak == 0.1 13:53:57 INFO - mozlog == 3.4 13:53:57 INFO - moznetwork == 0.27 13:53:57 INFO - mozprocess == 0.25 13:53:57 INFO - mozprofile == 0.28 13:53:57 INFO - mozrunner == 6.13 13:53:57 INFO - mozscreenshot == 0.1 13:53:57 INFO - mozsystemmonitor == 0.3 13:53:57 INFO - moztest == 0.8 13:53:57 INFO - mozversion == 1.4 13:53:57 INFO - psutil == 3.1.1 13:53:57 INFO - requests == 1.2.3 13:53:57 INFO - wsgiref == 0.1.2 13:53:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:57 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:57 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 13:53:57 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 13:53:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:57 INFO - 'HOME': '/Users/cltbld', 13:53:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:57 INFO - 'LOGNAME': 'cltbld', 13:53:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:57 INFO - 'MOZ_NO_REMOTE': '1', 13:53:57 INFO - 'NO_EM_RESTART': '1', 13:53:57 INFO - 'PAGER': '/bin/cat', 13:53:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:57 INFO - 'PWD': '/builds/slave/test', 13:53:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:57 INFO - 'SHELL': '/bin/bash', 13:53:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:57 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:57 INFO - 'USER': 'cltbld', 13:53:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:57 INFO - 'XPC_FLAGS': '0x0', 13:53:57 INFO - 'XPC_SERVICE_NAME': '0', 13:53:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 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)) 13:53:58 INFO - Cleaning up... 13:53:58 INFO - Return code: 0 13:53:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:53:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:53:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:53:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:53:58 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 0x105deeb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105df1768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcb815a8150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, '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.X0MI16m5TB/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.rl6XGodboW/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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 13:53:58 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 13:53:58 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 13:53:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:53:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:53:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:53:58 INFO - 'HOME': '/Users/cltbld', 13:53:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:53:58 INFO - 'LOGNAME': 'cltbld', 13:53:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:53:58 INFO - 'MOZ_NO_REMOTE': '1', 13:53:58 INFO - 'NO_EM_RESTART': '1', 13:53:58 INFO - 'PAGER': '/bin/cat', 13:53:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:53:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:53:58 INFO - 'PWD': '/builds/slave/test', 13:53:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:53:58 INFO - 'SHELL': '/bin/bash', 13:53:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:53:58 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:53:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:53:58 INFO - 'USER': 'cltbld', 13:53:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:53:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:53:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:53:58 INFO - 'XPC_FLAGS': '0x0', 13:53:58 INFO - 'XPC_SERVICE_NAME': '0', 13:53:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:53:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:53:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:53:58 INFO - Cleaning up... 13:53:58 INFO - Return code: 0 13:53:58 INFO - Running post-action listener: _resource_record_post_action 13:53:58 INFO - Running post-action listener: _start_resource_monitoring 13:53:58 INFO - Starting resource monitoring. 13:53:58 INFO - [mozharness: 2017-04-09 20:53:58.552028Z] Finished create-virtualenv step (success) 13:53:58 INFO - [mozharness: 2017-04-09 20:53:58.553001Z] Running install step. 13:53:58 INFO - Running pre-action listener: _resource_record_pre_action 13:53:58 INFO - Running main action method: install 13:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:53:58 INFO - Reading from file tmpfile_stdout 13:53:58 INFO - Detecting whether we're running mozinstall >=1.0... 13:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:53:58 INFO - Reading from file tmpfile_stdout 13:53:58 INFO - Output received: 13:53:58 INFO - Usage: mozinstall [options] installer 13:53:58 INFO - Options: 13:53:58 INFO - -h, --help show this help message and exit 13:53:58 INFO - -d DEST, --destination=DEST 13:53:58 INFO - Directory to install application into. [default: 13:53:58 INFO - "/builds/slave/test"] 13:53:58 INFO - --app=APP Application being installed. [default: firefox] 13:53:58 INFO - mkdir: /builds/slave/test/build/application 13:53:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 13:53:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 13:54:19 INFO - Reading from file tmpfile_stdout 13:54:19 INFO - Output received: 13:54:19 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 13:54:19 INFO - Running post-action listener: _resource_record_post_action 13:54:19 INFO - [mozharness: 2017-04-09 20:54:19.362113Z] Finished install step (success) 13:54:19 INFO - [mozharness: 2017-04-09 20:54:19.362253Z] Running run-tests step. 13:54:19 INFO - Running pre-action listener: _resource_record_pre_action 13:54:19 INFO - Running main action method: run_tests 13:54:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:54:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:54:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:54:19 INFO - 2017-04-09 13:54:19.376 screenresolution[1631:7589] starting screenresolution argv=screenresolution get 13:54:19 INFO - 2017-04-09 13:54:19.379 screenresolution[1631:7589] Display 0: 1600x1200x32@60 13:54:19 INFO - 2017-04-09 13:54:19.385 screenresolution[1632:7591] starting screenresolution argv=screenresolution list 13:54:19 INFO - Available Modes on Display 0 13:54:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:54:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:54:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:54:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:54:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:54:19 INFO - Intel Iris: 13:54:19 INFO - Chipset Model: Intel Iris 13:54:19 INFO - Type: GPU 13:54:19 INFO - Bus: Built-In 13:54:19 INFO - VRAM (Dynamic, Max): 1536 MB 13:54:19 INFO - Vendor: Intel (0x8086) 13:54:19 INFO - Device ID: 0x0a2e 13:54:19 INFO - Revision ID: 0x0009 13:54:19 INFO - Displays: 13:54:19 INFO - Display: 13:54:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:54:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:54:19 INFO - Display Serial Number: 1600x1200 60 13:54:19 INFO - Main Display: Yes 13:54:19 INFO - Mirror: Off 13:54:19 INFO - Online: Yes 13:54:19 INFO - Rotation: Supported 13:54:19 INFO - Return code: 0 13:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:54:19 INFO - Python 2.7.3 13:54:19 INFO - Return code: 0 13:54:19 INFO - grabbing minidump binary from tooltool 13:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:54:19 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 0x105bccd30>, '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 0x105af3db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105bc1be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:54:19 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 13:54:19 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 13:54:19 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 13:54:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:54:19 INFO - Return code: 0 13:54:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:54:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:54:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:54:19 INFO - ENV: RUST_BACKTRACE is now 1 13:54:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:54:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--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 13:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0250 --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 13:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.X0MI16m5TB/Render', 13:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:54:19 INFO - 'HOME': '/Users/cltbld', 13:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:54:19 INFO - 'LOGNAME': 'cltbld', 13:54:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:54:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:54:19 INFO - 'MOZ_NO_REMOTE': '1', 13:54:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:54:19 INFO - 'NO_EM_RESTART': '1', 13:54:19 INFO - 'PAGER': '/bin/cat', 13:54:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:54:19 INFO - 'PWD': '/builds/slave/test', 13:54:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:54:19 INFO - 'RUST_BACKTRACE': '1', 13:54:19 INFO - 'SHELL': '/bin/bash', 13:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.rl6XGodboW/Listeners', 13:54:19 INFO - 'TMPDIR': '/var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/', 13:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:54:19 INFO - 'USER': 'cltbld', 13:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:54:19 INFO - 'XPC_FLAGS': '0x0', 13:54:19 INFO - 'XPC_SERVICE_NAME': '0', 13:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:54:19 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0250', '--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 13:54:19 INFO - mozversion application_buildid: 20170409123541 13:54:19 INFO - mozversion application_changeset: 45692c884fdd5136a64fb2f8a61a0c8183b69331 13:54:19 INFO - mozversion application_display_name: Nightly 13:54:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:54:19 INFO - mozversion application_name: Firefox 13:54:19 INFO - mozversion application_remotingname: firefox 13:54:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:54:19 INFO - mozversion application_vendor: Mozilla 13:54:19 INFO - mozversion application_version: 55.0a1 13:54:19 INFO - mozversion platform_buildid: 20170409123541 13:54:19 INFO - mozversion platform_changeset: 45692c884fdd5136a64fb2f8a61a0c8183b69331 13:54:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:54:19 INFO - mozversion platform_version: 55.0a1 13:54:19 INFO - using testdate: 1491771259 13:54:19 INFO - actual date: 1491771259 13:54:19 INFO - starting webserver on 'localhost:49242' 13:54:19 INFO - SUITE-START | Running 2 tests 13:54:19 INFO - TEST-START | dromaeo_css 13:54:19 INFO - operating with platform_type : mac_ 13:54:19 INFO - Initialising browser for dromaeo_css test... 13:54:20 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmp5RTykK/profile http://localhost:49242/getInfo.html) 13:54:23 INFO - PID 1642 | 2017-04-09 13:54:23.226 plugin-container[1645:7780] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c53, name = 'com.apple.tsm.portname' 13:54:23 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:54:23 INFO - PID 1642 | 2017-04-09 13:54:23.227 plugin-container[1645:7780] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x970b, name = 'com.apple.CFPasteboardClient' 13:54:23 INFO - PID 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:54:23 INFO - PID 1642 | 2017-04-09 13:54:23.227 plugin-container[1645:7780] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:54:23 INFO - PID 1642 | __metrics Screen width/height:1600/1200 13:54:23 INFO - PID 1642 | colorDepth:24 13:54:23 INFO - PID 1642 | Browser inner width/height: 1024/689 13:54:23 INFO - PID 1642 | __metrics 13:54:23 ERROR - PID 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 13:54:24 INFO - TEST-INFO | 1642: exit 0 13:54:24 INFO - Browser initialized. 13:54:24 INFO - Running cycle 1/1 for dromaeo_css test... 13:54:24 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmp5RTykK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:54:27 INFO - PID 1646 | 2017-04-09 13:54:27.109 plugin-container[1647:7906] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 13:54:27 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:54:27 INFO - PID 1646 | 2017-04-09 13:54:27.110 plugin-container[1647:7906] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d17, name = 'com.apple.CFPasteboardClient' 13:54:27 INFO - PID 1646 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:54:27 INFO - PID 1646 | 2017-04-09 13:54:27.110 plugin-container[1647:7906] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:54:36 INFO - PID 1646 | RSS: Main: 235388928 13:54:36 INFO - PID 1646 | 13:57:42 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49242/tests/dromaeo/cssquery-ext.html) 13:57:43 INFO - PID 1646 | RSS: Main: 252080128 13:57:43 INFO - PID 1646 | 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 13:59:24 INFO - PID 1646 | 13:59:24 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:59:24 INFO - PID 1646 | Full stack: 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 13:59:24 INFO - PID 1646 | 13:59:24 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:59:24 INFO - PID 1646 | Full stack: 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 13:59:24 INFO - PID 1646 | 13:59:24 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:59:24 INFO - PID 1646 | Full stack: 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | ************************* 13:59:24 INFO - PID 1646 | A coding exception was thrown and uncaught in a Task. 13:59:24 INFO - PID 1646 | 13:59:24 ERROR - PID 1646 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:59:24 INFO - PID 1646 | Full stack: 13:59:24 INFO - PID 1646 | ************************* 14:00:45 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-ext.html (next: http://localhost:49242/tests/dromaeo/cssquery-jquery.html) 14:00:46 INFO - PID 1646 | RSS: Main: 260063232 14:00:46 INFO - PID 1646 | 14:03:47 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49242/tests/dromaeo/cssquery-mootools.html) 14:03:48 INFO - PID 1646 | RSS: Main: 271933440 14:03:48 INFO - PID 1646 | 14:06:50 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49242/tests/dromaeo/cssquery-prototype.html) 14:06:50 INFO - PID 1646 | RSS: Main: 264548352 14:06:50 INFO - PID 1646 | 14:09:53 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49242/tests/dromaeo/cssquery-yui.html) 14:09:54 INFO - PID 1646 | RSS: Main: 274800640 14:09:54 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | Cycle 1(1): loaded http://localhost:49242/tests/dromaeo/cssquery-yui.html (next: http://localhost:49242/tests/dromaeo/cssquery-dojo.html) 14:12:56 INFO - PID 1646 | RSS: Main: 269053952 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | __start_tp_report 14:12:56 INFO - PID 1646 | _x_x_mozilla_page_load 14:12:56 INFO - PID 1646 | _x_x_mozilla_page_load_details 14:12:56 INFO - PID 1646 | |i|pagename|runs| 14:12:56 INFO - PID 1646 | |0;dojo.html;3050.954235686465;3586.28274345131;3587.2287458196483;3608.8917332480037;3655.945160822588;6156.784512542062;6220.595661282019;6259.655718935457;6286.49708023357;6329.019002054689;6153.907691384622;6200.255969283692;6241.282252540947;6295.401936816007;6310.463610593094;5543.168524721284;5581.134924086773;5644.266245388094;5654.886902261952;5689.573282003846;5488.945110548883;5562.3047119110115;5686.260786097801;5697.116946873241;5746.367899531048;5501.312335958005;5538.307711536058;5587.3015873015875;5600.215969764236;5628.662280263197;5512.366077901052;5557.499825015728;5577.637453565546;5580.748866301026;5594.804181853657;5397.379301380352;5505.944940550583;5508.20131080995;5521.061419558685;5537.889242215173;6132.080177995968;6200.844978875519;6204.47261982732;6230.96884515579;6248.593841400295;6794.048833163361;6805.48958828085;6814.318568143196;6821.658917054177;6830.112085428863;6830.31696830313;6880.483963702693;6886.208086070116;6899.241083480813;6918.377346038881;6194.783182588588;6205.875882482325;6261.467775239064;6352.428281454599;6354.618722876642;7027.648617569203;7044.788656340318;7056;7115.92884071163;7129.82175445618;6416.679166041773;6540.6729663516635;6543.9345606544275;6735.562188457735;6738.393544581011;6284.874302513924;6571.817072926919;6890.931090689029;6896.7586134485055;6901.413379862667;6528.151340325728;6782.69477873487;6809;6809.557378770364;6842.281560436182;5728.484436400744;5789.826305210766;6621.702023408958;6712.731490740316;6772.661366931634;5416.891662166735;5437.401885792559;5441.782328706887;5443.564514838803;5447.618666693294;4199.887029937069;4784.545468180518;4787.736674482867;4807.062622788592;4859.902801943941;5315.946840531623;5981.491573216325;6006.12911127884;6207.472364849038;6422;6873.5875847448315;6928.203256625553;6931.133608298963;6934.4452443804375;6947;5448.645838020516;6365.554411191172;6430.935690643128;6443.226812782496;6467.579607325509;5858.443447872445;5915.37888521699;5918.911216331759;5921.970390148022;6068.878622427526;6260.248770147611;6745.93254067453;6885.173779146535;7005.2644472329675;7029.507934444539;5921.904447677193;6912.239653638197;6936.375726184668;6937.410320122846;6941.167059952838;6768.830779230559;7050.964745176241;7089.929100708927;7093.113360829896;7093.751718689821;6641.103451034049;6720.798376048726;6742.966285168739;6787.457003439812;6796.694148763218;6260.467860231931;6382.712777925098;6909.963505474219;6938.098047253825;6944.027836102849;6010.887985722656;6450.322716114928;6475.838104047437;6598.340165983555;6695.12963314766;5558.416366281483;6623.105880706235;6674.733010679713;6684.16447944007;6706.161729783777;6248.062790581449;6366.586171898812;6384.361563843764;6452.645104519109;6538.738450461929;6672.466202703675;6676.766313179015;6687.765928192684;6711.0940023096255;6724.630145341857;5459;6476.70854811544;6490.448311893542;6500.5449618526245;6519.282878883414 14:12:56 INFO - PID 1646 | |1;ext.html;694.8992396102565;730.5142080516454;818.0388044048244;1265.183956348156;1280.3406245783417;37157.25685486292;37988.620113798854;38022.80988595056;38034.61965380346;38166.236675266475;38030.049248768795;38050.23899522015;38381.232375352476;38425.23149537008;38448.423273650915;8058.476199047072;8560.186782255676;8794.604242809057;8798.736037918872;8801.29589632831;8672.826543469128;8699.217070463657;8767.21095101444;8803.031666516677;8811.95594022029;8755.649774009064;8762.079981601935;8773.25427338677;8811.251043661296;8859.601317940676;8721.476711397305;8795.428297160695;8802.251808596213;8804.251638610662;8814.162654547808;8488.618012189405;8538.359623028236;8556.18716221958;8570.614322355508;8575.699850505265;37200.44199337012;37325.81337093297;37329.88010359694;37439.43840842389;37542.62457375418;3614.367485689994;3634.909127271813;3664.670179683841;3672.889813305605;3675.558932928039;30490.84754576235;30618.234544136576;30626;30694.079177624706;30785.076447706604;2732.617433559303;3208.7112159905487;3220.806751594912;3232.111169428426;3237.1583388318973;231334;236191;278960.99999999796;300698;303627;47992.520074799504;48030.75984620055;48044;48107.75946120247;48272.51727482751;50040.99918001692;50259.24611130836;50270.497295027315;50389.99220015579;50454.49545504498;14299.64250893736;14325.64185895361;14459.27703614815;14487.420503179756;14491.85508144905;11732.824007639892;11793.52825886955;11873;11876.406179691154;11888.28670279786;13189.889617661129;13454.93272533631;13459.461621535225;13491.52779652727;13575.524856630169;5473.863153421196;5667.206591077253;5898.705064746746;5945.613535120204;5978.372270911578;1052.4739128263896;1054.778496515725;1059.1103473082494;1081.1566977757361;1083.6207327435493;30139.547906781416;30406.695933040388;30482;30561.235969100504;30831.8458407711;1109.1902910875106;1109.2180013090751;1143.1826244235413;1144.3648774929827;1145.478807142748;10991.725206869893;11647.184697071123;12406.25562466255;12461.065420093348;12522.311272880079;375279.7192308844;379505;379708;380675;381527;51936.740316298936;52189.21716174261;52236.43290701285;52414.73792631089;52704.736476317375;1466.1789397937189;1496.4687535924809;1521.6043828604643;1525.3441020361129;1538.277009805412;22813.201537946094;23815.8092095398;23848;23899.163529276393;23930.76069239355;37963.05092372602;38014.80992595019;38123.80938095292;38427.807860960515;38442;347570;348067;349373;350124;350164;32845.850395236055;36106.09734756652;36680.89957301178;36691.26617467689;36788.63211367852;37960.62039379571;44767;46033.76983115197;46046;46174;29853.5521967175;30564.235894101937;30999.380012400077;31179.220519487193;31504.684953150176;30107.849460753434;30220;30948.53577196389;30974.0707778767;31242.375152496366;37084.44373334348;37165.81417092897;37307.81346093252;37314.6268537322;37343.81328093342;28457.857710711312;28665.710043047697;29940.850295748383;31664.525032124075;31962.840185798923;21182.47043823855;23854.642180366962;24346.00419036245;24948.37629059288;25152.37119072038 14:12:56 INFO - PID 1646 | |2;jquery.html;400.40139990713885;435.4883012460358;436.4805881001607;448.14851781614925;453.53965724789333;19341.806581934175;21041.894790526047;21527.892360538215;21744.673829892545;21825.23611673588;21942;22750;23143.842807859583;23203.535929281403;23373.532529349402;6132.356102609228;6318.842028949267;6370.108184854124;6371.776987805428;6376.330485299047;5961.701914904259;6203.9069413958605;6243.063540468921;6256.405641464054;6258.812235632939;6135.079738039308;6135.447809697126;6201.782937597185;6207.720652570622;6221.875562488747;6138.815835524942;6265.624062556216;6292.1820163378925;6294.307626161119;6306.432421082101;4456.732596044248;4491.640668746492;4507.57178068083;5031.968446468465;5589.133684278943;27877.58183627247;28396.716032839613;28449.715502844912;28477.288067798472;28802;2223.7442694090064;2289.8740569268684;2297.8506397084134;2300.723913130418;2302.861828290308;14958.102513849204;16847.32610695571;17769.289228430847;18154.727679084823;18350.449486515427;2144.6997420361163;2170.848040637156;2184.159098746978;2209.6795964585044;2216.4927849062633;134347;140299;162348;173816;176183;12335.876641233473;13009.154404963647;13025;13027.413766380347;13047.412866421033;16607.50177494677;16640.6671866562;16641.916790416213;16700.582485437717;16714.832851671326;8745.990924346415;9060.728178154666;9067.727968160834;9140.497272649935;9227.861582076273;7524.548527088392;8455.492670439793;8626.482411055356;8642.395032347675;8838;7791.220877912277;7791.818290878563;7877.448578599558;7921.207879212033;7956.721514746956;1880.4922670878786;2180.5529866377674;2188.5841690078832;2192.5066859956382;2404.2426635609727;941.6841927320376;943.5470973932413;973.2846357926918;978.9461579613189;1010.5200029985698;19409.320673776634;20126.39620811378;20605.38183854487;20681.276155334792;20848.791512084987;992.2161492420906;1018.9750485581981;1021.7445638590352;1023.5854478936043;1034.4362322534237;8973.109655237911;10400.375977441377;10696.304740192018;10709.78580428387;10788.51451684676;699911;715864;718612;724782.3760881162;741708;153612;158168;160825;163039.18480407522;169501;1202.296656455983;1225.375058720059;1269.4005553003283;1270.7966725323904;1273.9299338536468;21147.577048458636;21204.501257292373;22215.77784222137;22233;22534;49446.50553494419;64379.03431448439;64398.67800660967;64432.67783661239;64992;87293.56353218193;89571.55214223887;90321;90495.54752226197;91000.54499727723;7552.584607846621;8821;8850.822983540163;8855.33584981142;8929.508877011827;13736.725265494833;14006.885211698245;14074.718505630035;14155.858441415454;14194.432222710975;11588.768224635629;11619.128565357802;11976.341301228516;12032.879671203176;12139.271643701406;11185.552577897119;11226.775464490827;11232.213745037769;11348.319100853974;11439.370834604177;11590.71023224426;11783.76432471363;12112.273263604213;12125.514979400725;12275;7619.69521219145;8474.66101355939;8586.871196931927;8755.824883502422;8888.155625215593;9968.55141518619;10849.294795838245;11047.226694131334;12031.217970831867;12146.271223726604 14:12:56 INFO - PID 1646 | |3;mootools.html;1333.2000799520285;1467.6864206535151;1468.1484738851468;1480.5040311495648;1496.543554215964;11462.254953428032;11952.7609447811;12043.51825926964;12110.697232569179;12218.816717749241;11969.521219151224;12107.57623483176;12111;12142.392880355968;12162.939185304062;2539.5047965646704;2579.1746641074865;2579.2262321303533;2582.6771653543306;2594.07910191882;2475.047510736681;2541.0344069253656;2543.313305407539;2560.2703229579574;2561.065211197909;2466.7903228225623;2532.15172917073;2537.619357096432;2548.987255063722;2551.119863647039;2456.226288719057;2510.7614776596192;2530.02594001309;2538.3780973661387;2542.9618555721495;2441.40185654514;2527.4060595759984;2537.3529749913655;2543.758342957416;2554.246497283306;12104;12166.695832604168;12178.208416452992;12186.329751863645;12189.756204875854;6236.719347629368;6874.759383421556;6876.656167191621;6878.965605171992;6885.311468853125;4181.540030596632;4450.732956022649;4460.933086003713;4491.483479399878;4527.7736113194205;1263.2736176698436;1264.4373253902017;1269.3018839638162;1295.7149427126112;1318.1168617026615;15188;15868.761968570481;16803.41188058412;16899.577510562212;16909.74635380458;5564.248826408464;5715.657060576379;5925.288965324189;5929.169916211734;5942.732577034044;12368.938155309166;12436.067294952914;12472.81290780639;12482.251064936132;12514.624561263177;6643.236027856762;6675.699593518303;6676.0987266718375;6676.399124078759;6676.46588272937;5964.433378829004;6125.41808528198;6129.754809807647;6179.783707570214;6194.628322300587;4367.934480982788;4481.5070342262325;4512;4526.59260666535;4530.977345113253;1297.5977446991462;1324.6224825924758;1328.800679898023;1335.1121504199598;1344.5495758920872;809.6842231529708;826.801567623766;843.4348986179275;850.7277671145174;874.2612492444002;6203.627782333075;6283.120363149163;6313;6434.195725534309;6554.508411869129;588.2558563417281;603.809799913026;613.1109890658563;614.2598169206096;616.6608365399013;4137.379393091061;4356.607905288539;4407.206702793528;4498.167838949805;4533.093381323749;14666.633334166518;15225.162616056;15227.695446091016;15236.771448428282;15261.771073433907;13446.663833404047;15153.393864245527;15163.545093647208;15394.692106158038;15395.846041539666;10625.096866766411;10912.945435272932;10921;10938.398388088732;10945.781084378426;6151.200343955257;6310.318114801283;7052.506324557232;7230.819229519304;7297.489175757858;12594.685132871751;13539.255340956342;13543;13682.657933551343;13730.519431820238;11269.887301127212;11518.481668324777;13496.055276130577;13848.584542463743;13990.300484975507;9598.616055357987;9683.225341972766;9806.068423499755;9815.950920245354;9819.607215711292;10968.890311096788;10985.39580323058;11060.557577696802;11099.667009989713;11104.777904442028;9039.322050846344;9453.95273023658;9665.275104367342;9719.319647624881;9721.319507634398;9424.717258482257;9427.57575909072;9497.715068547956;9524.809503809747;9614.326997110134;8035.437519373588;8163.918360816316;8251.174882511701;8254.298384637374;8268.2558569729;5174.430812610534;6067.54493412987;6073.696315184311;6180.412860778219;6312.5581209314905;6136.325004249618;6257.217847769029;6299.685015749286;6304.968475157595;6361.554691171574 14:12:56 INFO - PID 1646 | |4;prototype.html;328.80929061144536;383.23162060069575;397.4217513517832;401.530209654704;406.4837656176655;6174.9382506174925;6356.555041147116;6371.840703982402;6377.553571250009;6382.553221274513;5769.682667453299;6099.237595300588;6299.842503937392;6347;6361.809145725636;4810.374651295336;4820.662553621248;4820.867096232382;4829.468758436569;4853.830115945942;4642.048380082075;4713.033828065256;4741.122892264942;4792;4803.447603525587;4749.4063242094735;4750.311204875308;4751.263554149113;4753.714777113367;4770.4513980892125;4554.180247555439;4770.093682200388;4778.661974647104;4809.014152098812;4814.855554333376;4031.5363733170475;4206.221848957922;4215.789210539491;4225.830966761357;4252.362840306473;5068.898622027576;6038.396160383971;6095.817125486198;6138.877222455526;6162.383761623802;4702.412198475191;5393.946060539383;5403.621746477748;5683.8010669626365;5717.9714101429645;6538;6696.06255124283;6697.698603562802;6703.731850725965;6706.798796036127;4065.6989763275765;4554.2485489894125;4809.254565542346;4809.5430934061205;4809.831655892062;6753.932460675429;6985.266547012592;7047.92952070483;7062.788116356517;7176.497645164841;4647.814087436464;4665.580097791215;4677;4689.273162659793;4698.483166851644;3841.193349396602;4682.859514214579;4791;4796.616270698336;4802.351682522884;4921.0157968406465;5300.761465734051;5461.1808228766;5550.3626430203185;5569.888602227933;6097.719478909478;6168.753249869955;6363.840903977345;6667.666616669244;6671.966640166768;1488.7692407676827;1531.9157446340557;1615.2569817883905;1720.8967461952323;1730.9221085051204;1038.6341960322152;1162.511745067074;1171.5882361754066;1182.3024415594841;1184.9407529623484;6218.3673163492585;6226.6575338355915;6237.625742455467;6257;6257.217847769029;6305.716242768995;6793.932060679429;6836.794896153124;6845.178578570504;6870.3473170048765;5580.098348858571;5850.12248162779;5931.58478906481;6239.688015599202;6251.78118765841;3574.7318951078255;3599.8380072896784;3612.7290453216115;3615.6565126312958;3619.819009049537;5731.398203188689;6797.014432907201;6881.587104773629;7066.7173313066905;7095;5466.64466809664;6376.53339731855;6779.3220677932795;7018.368346848808;7075.620254050411;5809.680467574324;5861.062230043172;6296.748130074838;6802.455803535705;7041;6287.46556542699;6832.453403727591;6858.348456896587;6891.27641597625;6896.517243793087;5545.973994810973;5844.013035567928;6019;6423.261324947504;6735.922252439586;5711.657300561979;6273.874522509433;7085.50401471892;7109.653814241391;7121.216666166614;4362.454693163355;4456.264716321771;4458.974435879831;4478.081993191323;4535.387722657399;5741.540676745767;6912.792616221522;6913.170419549686;6913.896291555731;6945.930540694529;6441.1626488556185;6507.893658078233;6550.574212676161;6681.766138185335;6729.798106056826;6451.806445806634;6456.322086180883;6629.7679581214425;6665.733370665119;6696;5453.836384908459;6518.282988871315;6522.902156467562;6536.607803531803;6549.967250163719;5110.61670374716;5804.200697783701;6137.7851775187655;6576.881930071995;6631.834204144935;5370.4629537045985;5612.691301978431;6489.448396886129;6495.253045899593;6511.692970229248 14:12:56 INFO - PID 1646 | |5;yui.html;834.6160766047625;842.3261390887288;860.4106187261722;883.7348795361391;885.3890815337417;13503.662408439794;14384.640383990405;14990.475333363334;16544.17279136043;16889.746653800186;16654.66690666186;16985.660286794257;17256.807490614246;17399.956002639818;17455;953.8950715421298;968.7142293023539;970.6457143142773;971.504532688327;972.2465089555616;971.7813491964322;994.0606127209753;995.1640621877622;996.9880571220217;998.3610489286863;1049.459528342904;1051.6634676903393;1052.7578656908915;1054.662507997441;1056.8097742406364;1031.0771859186061;1045.947702614866;1046.7435478307825;1048.1771809129796;1055.2033214922722;777.0985656638329;779.9805004874867;782.12402109637;782.2451334462271;785.9371250299962;15303.005304655162;16638.83361166385;16759.497215083567;17079.402220922333;17313.567160820956;16772.664546709;17131;17188.484345469657;17375.826241737548;17457.738133928;13938.512152074629;14001.71996560063;14144.29278536069;14149.292535373294;14328.570142895729;3514.947275790865;3528.7177025838128;3551.5738111426535;3560;3564.465330200491;102923.99999999926;103065.4846725769;103220.96779032264;103458;104376;13293.069485135948;13313.134646248156;13422.798658020138;13455.327233638474;13507.662308442368;23830.642540361907;24908;25029.37426564314;25316.620250696255;25984.480310393687;7673.426947475754;7938.9206107939335;8557.828843423096;8892.777680558038;8952.820943581091;4398.560143985575;4806.423229212517;4827.106985207748;4907.33750943618;4920.630952678492;4547.954520454819;4752.120857641347;4754.405699287589;4795.904081918342;4802.447718512346;924.2421214604094;955.6894009446958;959.0792838874759;966.4587830814767;967.3905439573024;670.7954074007474;680.4080450008524;683.2279524137692;691.2810676895972;702.2766550453042;10311.91724868893;10606.575736970435;11248.212625116163;11478;12435.93782031084;645.096864389858;670.4669787518934;677.7695583501535;691.1153723234269;692.1071817355655;4905.2151655734915;5486.451354864482;5491.203775452537;5580.86047848807;5831.94168058314;104337;105955;106423.46788266009;106488;109337.45331273293;93190;94042;94695.57956630657;96000.55999160018;96919.51540242395;65828;67283.32716672869;67952.3204767946;68000.65999670069;69205.26986825268;11862.58480953197;12084.395780210778;12612.495500180257;12616.87383126157;12629.684257893627;34611.653883460844;34779.478307824895;35572.28855422825;36624.63375366212;36963;46771.532284676716;47527;47761.28358074562;66761.33238667551;67670;11448.828267575826;15355.309011094305;16368.67262654764;17008.979461232364;17125.57186070358;17951.551211219823;18220.90889545544;18514.629707405507;18597.81402185961;18786.718199227296;16001.679966400374;16694.582635433726;16857.662846743242;17039.062851543284;17693.496052835653;16866.409675661784;17351.913240433718;17389;17403;17587.736183956997;15229.771553426486;16719.24763385675;16883.91558042202;16961.406350777663;17197.968121912727;11767.23512971654;11916.940415297868;11998.880011199777;12099.33453660057;12119.091068170099;12423.875761242272;12808.551700690432;12875.356232188165;12918.806217906927;13085.542006030124 14:12:56 INFO - PID 1646 | __end_tp_report 14:12:56 INFO - PID 1646 | __start_cc_report 14:12:56 INFO - PID 1646 | _x_x_mozilla_cycle_collect,422 14:12:56 INFO - PID 1646 | __end_cc_report 14:12:56 INFO - PID 1646 | __startTimestamp1491772376458__endTimestamp 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | ------- Summary: start ------- 14:12:56 INFO - PID 1646 | Number of tests: 6 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | [#0] dojo.html Cycles:165 Average:6210.55 Median:6382.71 stddev:758.69 (11.9%) stddev-sans-first:719.39 14:12:56 INFO - PID 1646 | Values: 3051.0 3586.3 3587.2 3608.9 3655.9 6156.8 6220.6 6259.7 6286.5 6329.0 6153.9 6200.3 6241.3 6295.4 6310.5 5543.2 5581.1 5644.3 5654.9 5689.6 5488.9 5562.3 5686.3 5697.1 5746.4 5501.3 5538.3 5587.3 5600.2 5628.7 5512.4 5557.5 5577.6 5580.7 5594.8 5397.4 5505.9 5508.2 5521.1 5537.9 6132.1 6200.8 6204.5 6231.0 6248.6 6794.0 6805.5 6814.3 6821.7 6830.1 6830.3 6880.5 6886.2 6899.2 6918.4 6194.8 6205.9 6261.5 6352.4 6354.6 7027.6 7044.8 7056.0 7115.9 7129.8 6416.7 6540.7 6543.9 6735.6 6738.4 6284.9 6571.8 6890.9 6896.8 6901.4 6528.2 6782.7 6809.0 6809.6 6842.3 5728.5 5789.8 6621.7 6712.7 6772.7 5416.9 5437.4 5441.8 5443.6 5447.6 4199.9 4784.5 4787.7 4807.1 4859.9 5315.9 5981.5 6006.1 6207.5 6422.0 6873.6 6928.2 6931.1 6934.4 6947.0 5448.6 6365.6 6430.9 6443.2 6467.6 5858.4 5915.4 5918.9 5922.0 6068.9 6260.2 6745.9 6885.2 7005.3 7029.5 5921.9 6912.2 6936.4 6937.4 6941.2 6768.8 7051.0 7089.9 7093.1 7093.8 6641.1 6720.8 6743.0 6787.5 6796.7 6260.5 6382.7 6910.0 6938.1 6944.0 6010.9 6450.3 6475.8 6598.3 6695.1 5558.4 6623.1 6674.7 6684.2 6706.2 6248.1 6366.6 6384.4 6452.6 6538.7 6672.5 6676.8 6687.8 6711.1 6724.6 5459.0 6476.7 6490.4 6500.5 6519.3 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | [#1] ext.html Cycles:180 Average:48081.59 Median:3649.79 stddev:88650.70 (2428.9%) stddev-sans-first:88827.99 14:12:56 INFO - PID 1646 | Values: 694.9 730.5 818.0 1265.2 1280.3 37157.3 37988.6 38022.8 38034.6 38166.2 38030.0 38050.2 38381.2 38425.2 38448.4 8058.5 8560.2 8794.6 8798.7 8801.3 8672.8 8699.2 8767.2 8803.0 8812.0 8755.6 8762.1 8773.3 8811.3 8859.6 8721.5 8795.4 8802.3 8804.3 8814.2 8488.6 8538.4 8556.2 8570.6 8575.7 37200.4 37325.8 37329.9 37439.4 37542.6 3614.4 3634.9 3664.7 3672.9 3675.6 30490.8 30618.2 30626.0 30694.1 30785.1 2732.6 3208.7 3220.8 3232.1 3237.2 231334.0 236191.0 278961.0 300698.0 303627.0 47992.5 48030.8 48044.0 48107.8 48272.5 50041.0 50259.2 50270.5 50390.0 50454.5 14299.6 14325.6 14459.3 14487.4 14491.9 11732.8 11793.5 11873.0 11876.4 11888.3 13189.9 13454.9 13459.5 13491.5 13575.5 5473.9 5667.2 5898.7 5945.6 5978.4 1052.5 1054.8 1059.1 1081.2 1083.6 30139.5 30406.7 30482.0 30561.2 30831.8 1109.2 1109.2 1143.2 1144.4 1145.5 10991.7 11647.2 12406.3 12461.1 12522.3 375279.7 379505.0 379708.0 380675.0 381527.0 51936.7 52189.2 52236.4 52414.7 52704.7 1466.2 1496.5 1521.6 1525.3 1538.3 22813.2 23815.8 23848.0 23899.2 23930.8 37963.1 38014.8 38123.8 38427.8 38442.0 347570.0 348067.0 349373.0 350124.0 350164.0 32845.9 36106.1 36680.9 36691.3 36788.6 37960.6 44767.0 46033.8 46046.0 46174.0 29853.6 30564.2 30999.4 31179.2 31504.7 30107.8 30220.0 30948.5 30974.1 31242.4 37084.4 37165.8 37307.8 37314.6 37343.8 28457.9 28665.7 29940.9 31664.5 31962.8 21182.5 23854.6 24346.0 24948.4 25152.4 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | [#2] jquery.html Cycles:180 Average:41788.06 Median:2293.86 stddev:120946.63 (5272.6%) stddev-sans-first:121245.99 14:12:56 INFO - PID 1646 | Values: 400.4 435.5 436.5 448.1 453.5 19341.8 21041.9 21527.9 21744.7 21825.2 21942.0 22750.0 23143.8 23203.5 23373.5 6132.4 6318.8 6370.1 6371.8 6376.3 5961.7 6203.9 6243.1 6256.4 6258.8 6135.1 6135.4 6201.8 6207.7 6221.9 6138.8 6265.6 6292.2 6294.3 6306.4 4456.7 4491.6 4507.6 5032.0 5589.1 27877.6 28396.7 28449.7 28477.3 28802.0 2223.7 2289.9 2297.9 2300.7 2302.9 14958.1 16847.3 17769.3 18154.7 18350.4 2144.7 2170.8 2184.2 2209.7 2216.5 134347.0 140299.0 162348.0 173816.0 176183.0 12335.9 13009.2 13025.0 13027.4 13047.4 16607.5 16640.7 16641.9 16700.6 16714.8 8746.0 9060.7 9067.7 9140.5 9227.9 7524.5 8455.5 8626.5 8642.4 8838.0 7791.2 7791.8 7877.4 7921.2 7956.7 1880.5 2180.6 2188.6 2192.5 2404.2 941.7 943.5 973.3 978.9 1010.5 19409.3 20126.4 20605.4 20681.3 20848.8 992.2 1019.0 1021.7 1023.6 1034.4 8973.1 10400.4 10696.3 10709.8 10788.5 699911.0 715864.0 718612.0 724782.4 741708.0 153612.0 158168.0 160825.0 163039.2 169501.0 1202.3 1225.4 1269.4 1270.8 1273.9 21147.6 21204.5 22215.8 22233.0 22534.0 49446.5 64379.0 64398.7 64432.7 64992.0 87293.6 89571.6 90321.0 90495.5 91000.5 7552.6 8821.0 8850.8 8855.3 8929.5 13736.7 14006.9 14074.7 14155.9 14194.4 11588.8 11619.1 11976.3 12032.9 12139.3 11185.6 11226.8 11232.2 11348.3 11439.4 11590.7 11783.8 12112.3 12125.5 12275.0 7619.7 8474.7 8586.9 8755.8 8888.2 9968.6 10849.3 11047.2 12031.2 12146.3 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | [#3] mootools.html Cycles:180 Average:7288.45 Median:2570.12 stddev:4575.51 (178.0%) stddev-sans-first:4566.46 14:12:56 INFO - PID 1646 | Values: 1333.2 1467.7 1468.1 1480.5 1496.5 11462.3 11952.8 12043.5 12110.7 12218.8 11969.5 12107.6 12111.0 12142.4 12162.9 2539.5 2579.2 2579.2 2582.7 2594.1 2475.0 2541.0 2543.3 2560.3 2561.1 2466.8 2532.2 2537.6 2549.0 2551.1 2456.2 2510.8 2530.0 2538.4 2543.0 2441.4 2527.4 2537.4 2543.8 2554.2 12104.0 12166.7 12178.2 12186.3 12189.8 6236.7 6874.8 6876.7 6879.0 6885.3 4181.5 4450.7 4460.9 4491.5 4527.8 1263.3 1264.4 1269.3 1295.7 1318.1 15188.0 15868.8 16803.4 16899.6 16909.7 5564.2 5715.7 5925.3 5929.2 5942.7 12368.9 12436.1 12472.8 12482.3 12514.6 6643.2 6675.7 6676.1 6676.4 6676.5 5964.4 6125.4 6129.8 6179.8 6194.6 4367.9 4481.5 4512.0 4526.6 4531.0 1297.6 1324.6 1328.8 1335.1 1344.5 809.7 826.8 843.4 850.7 874.3 6203.6 6283.1 6313.0 6434.2 6554.5 588.3 603.8 613.1 614.3 616.7 4137.4 4356.6 4407.2 4498.2 4533.1 14666.6 15225.2 15227.7 15236.8 15261.8 13446.7 15153.4 15163.5 15394.7 15395.8 10625.1 10912.9 10921.0 10938.4 10945.8 6151.2 6310.3 7052.5 7230.8 7297.5 12594.7 13539.3 13543.0 13682.7 13730.5 11269.9 11518.5 13496.1 13848.6 13990.3 9598.6 9683.2 9806.1 9816.0 9819.6 10968.9 10985.4 11060.6 11099.7 11104.8 9039.3 9454.0 9665.3 9719.3 9721.3 9424.7 9427.6 9497.7 9524.8 9614.3 8035.4 8163.9 8251.2 8254.3 8268.3 5174.4 6067.5 6073.7 6180.4 6312.6 6136.3 6257.2 6299.7 6305.0 6361.6 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | [#4] prototype.html Cycles:180 Average:5387.49 Median:5855.59 stddev:1617.06 (27.6%) stddev-sans-first:1576.39 14:12:56 INFO - PID 1646 | Values: 328.8 383.2 397.4 401.5 406.5 6174.9 6356.6 6371.8 6377.6 6382.6 5769.7 6099.2 6299.8 6347.0 6361.8 4810.4 4820.7 4820.9 4829.5 4853.8 4642.0 4713.0 4741.1 4792.0 4803.4 4749.4 4750.3 4751.3 4753.7 4770.5 4554.2 4770.1 4778.7 4809.0 4814.9 4031.5 4206.2 4215.8 4225.8 4252.4 5068.9 6038.4 6095.8 6138.9 6162.4 4702.4 5393.9 5403.6 5683.8 5718.0 6538.0 6696.1 6697.7 6703.7 6706.8 4065.7 4554.2 4809.3 4809.5 4809.8 6753.9 6985.3 7047.9 7062.8 7176.5 4647.8 4665.6 4677.0 4689.3 4698.5 3841.2 4682.9 4791.0 4796.6 4802.4 4921.0 5300.8 5461.2 5550.4 5569.9 6097.7 6168.8 6363.8 6667.7 6672.0 1488.8 1531.9 1615.3 1720.9 1730.9 1038.6 1162.5 1171.6 1182.3 1184.9 6218.4 6226.7 6237.6 6257.0 6257.2 6305.7 6793.9 6836.8 6845.2 6870.3 5580.1 5850.1 5931.6 6239.7 6251.8 3574.7 3599.8 3612.7 3615.7 3619.8 5731.4 6797.0 6881.6 7066.7 7095.0 5466.6 6376.5 6779.3 7018.4 7075.6 5809.7 5861.1 6296.7 6802.5 7041.0 6287.5 6832.5 6858.3 6891.3 6896.5 5546.0 5844.0 6019.0 6423.3 6735.9 5711.7 6273.9 7085.5 7109.7 7121.2 4362.5 4456.3 4459.0 4478.1 4535.4 5741.5 6912.8 6913.2 6913.9 6945.9 6441.2 6507.9 6550.6 6681.8 6729.8 6451.8 6456.3 6629.8 6665.7 6696.0 5453.8 6518.3 6522.9 6536.6 6550.0 5110.6 5804.2 6137.8 6576.9 6631.8 5370.5 5612.7 6489.4 6495.3 6511.7 14:12:56 INFO - PID 1646 | 14:12:56 INFO - PID 1646 | [#5] yui.html Cycles:180 Average:20840.17 Median:18086.23 stddev:28331.23 (156.6%) stddev-sans-first:28370.88 14:12:56 INFO - PID 1646 | Values: 834.6 842.3 860.4 883.7 885.4 13503.7 14384.6 14990.5 16544.2 16889.7 16654.7 16985.7 17256.8 17400.0 17455.0 953.9 968.7 970.6 971.5 972.2 971.8 994.1 995.2 997.0 998.4 1049.5 1051.7 1052.8 1054.7 1056.8 1031.1 1045.9 1046.7 1048.2 1055.2 777.1 780.0 782.1 782.2 785.9 15303.0 16638.8 16759.5 17079.4 17313.6 16772.7 17131.0 17188.5 17375.8 17457.7 13938.5 14001.7 14144.3 14149.3 14328.6 3514.9 3528.7 3551.6 3560.0 3564.5 102924.0 103065.5 103221.0 103458.0 104376.0 13293.1 13313.1 13422.8 13455.3 13507.7 23830.6 24908.0 25029.4 25316.6 25984.5 7673.4 7938.9 8557.8 8892.8 8952.8 4398.6 4806.4 4827.1 4907.3 4920.6 4548.0 4752.1 4754.4 4795.9 4802.4 924.2 955.7 959.1 966.5 967.4 670.8 680.4 683.2 691.3 702.3 10311.9 10606.6 11248.2 11478.0 12435.9 645.1 670.5 677.8 691.1 692.1 4905.2 5486.5 5491.2 5580.9 5831.9 104337.0 105955.0 106423.5 106488.0 109337.5 93190.0 94042.0 94695.6 96000.6 96919.5 65828.0 67283.3 67952.3 68000.7 69205.3 11862.6 12084.4 12612.5 12616.9 12629.7 34611.7 34779.5 35572.3 36624.6 36963.0 46771.5 47527.0 47761.3 66761.3 67670.0 11448.8 15355.3 16368.7 17009.0 17125.6 17951.6 18220.9 18514.6 18597.8 18786.7 16001.7 16694.6 16857.7 17039.1 17693.5 16866.4 17351.9 17389.0 17403.0 17587.7 15229.8 16719.2 16883.9 16961.4 17198.0 11767.2 11916.9 11998.9 12099.3 12119.1 12423.9 12808.6 12875.4 12918.8 13085.5 14:12:56 INFO - PID 1646 | -------- Summary: end -------- 14:12:56 INFO - PID 1646 | 14:12:57 INFO - TEST-INFO | 1646: exit 0 14:12:57 INFO - TEST-OK | dromaeo_css | took 1117116ms 14:12:57 INFO - TEST-START | kraken 14:12:57 INFO - operating with platform_type : mac_ 14:12:57 INFO - Initialising browser for kraken test... 14:12:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpST7Rra/profile http://localhost:49242/getInfo.html) 14:12:59 INFO - PID 1655 | 2017-04-09 14:12:59.864 plugin-container[1657:10212] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 14:12:59 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:12:59 INFO - PID 1655 | 2017-04-09 14:12:59.864 plugin-container[1657:10212] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 14:12:59 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:12:59 INFO - PID 1655 | 2017-04-09 14:12:59.864 plugin-container[1657:10212] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:13:00 INFO - PID 1655 | __metrics Screen width/height:1600/1200 14:13:00 INFO - PID 1655 | colorDepth:24 14:13:00 INFO - PID 1655 | Browser inner width/height: 1024/689 14:13:00 INFO - PID 1655 | __metrics 14:13:00 ERROR - PID 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3364: TypeError: this.tabs is undefined 14:13:01 INFO - TEST-INFO | 1655: exit 0 14:13:01 INFO - Browser initialized. 14:13:01 INFO - Running cycle 1/1 for kraken test... 14:13:01 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/94/wsdgpk794y1563fy307h6kq400000w/T/tmpST7Rra/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:13:03 INFO - PID 1658 | 2017-04-09 14:13:03.877 plugin-container[1659:10331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 14:13:03 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:13:03 INFO - PID 1658 | 2017-04-09 14:13:03.877 plugin-container[1659:10331] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x9003, name = 'com.apple.CFPasteboardClient' 14:13:03 INFO - PID 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:13:03 INFO - PID 1658 | 2017-04-09 14:13:03.877 plugin-container[1659:10331] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:13:13 INFO - PID 1658 | RSS: Main: 250023936 14:13:13 INFO - PID 1658 | 14:13:16 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection) 14:13:17 INFO - PID 1658 | RSS: Main: 259899392 14:13:17 INFO - PID 1658 | 14:13:20 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-dft) 14:13:20 INFO - PID 1658 | RSS: Main: 266924032 14:13:20 INFO - PID 1658 | 14:13:24 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-fft) 14:13:24 INFO - PID 1658 | RSS: Main: 261918720 14:13:24 INFO - PID 1658 | 14:13:26 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator) 14:13:27 INFO - PID 1658 | RSS: Main: 262836224 14:13:27 INFO - PID 1658 | 14:13:29 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:13:30 INFO - PID 1658 | RSS: Main: 263208960 14:13:30 INFO - PID 1658 | 14:13:33 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom) 14:13:33 INFO - PID 1658 | RSS: Main: 269721600 14:13:33 INFO - PID 1658 | 14:13:37 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate) 14:13:37 INFO - PID 1658 | RSS: Main: 270004224 14:13:37 INFO - PID 1658 | 14:13:40 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial) 14:13:41 INFO - PID 1658 | RSS: Main: 270102528 14:13:41 INFO - PID 1658 | 14:13:43 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:13:43 INFO - PID 1658 | RSS: Main: 269811712 14:13:43 INFO - PID 1658 | 14:13:45 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:13:46 INFO - PID 1658 | RSS: Main: 269762560 14:13:46 INFO - PID 1658 | 14:13:48 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:13:49 INFO - PID 1658 | RSS: Main: 269799424 14:13:49 INFO - PID 1658 | 14:13:52 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:13:53 INFO - PID 1658 | RSS: Main: 270065664 14:13:53 INFO - PID 1658 | 14:13:56 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:13:57 INFO - PID 1658 | RSS: Main: 267390976 14:13:57 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | Cycle 1(1): loaded http://localhost:49242/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49242/tests/kraken/driver.html?testName=ai-astar) 14:13:59 INFO - PID 1658 | RSS: Main: 270761984 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | __start_tp_report 14:13:59 INFO - PID 1658 | _x_x_mozilla_page_load 14:13:59 INFO - PID 1658 | _x_x_mozilla_page_load_details 14:13:59 INFO - PID 1658 | |i|pagename|runs| 14:13:59 INFO - PID 1658 | |0;ai-astar;114;122;124;124;123;123;126;123;122;121 14:13:59 INFO - PID 1658 | |1;audio-beat-detection;132;116;117;141;103;144;151;151;135;138 14:13:59 INFO - PID 1658 | |2;audio-dft;216;223;225;222;221;230;244;212;209;213 14:13:59 INFO - PID 1658 | |3;audio-fft;78;61;63;87;60;64;60;60;73;87 14:13:59 INFO - PID 1658 | |4;audio-oscillator;86;71;88;72;73;94;121;103;73;86 14:13:59 INFO - PID 1658 | |5;imaging-gaussian-blur;92;92;98;92;96;92;91;92;92;92 14:13:59 INFO - PID 1658 | |6;imaging-darkroom;158;101;103;103;103;103;131;100;101;155 14:13:59 INFO - PID 1658 | |7;imaging-desaturate;102;102;106;162;145;104;101;100;103;102 14:13:59 INFO - PID 1658 | |8;json-parse-financial;53;54;53;55;52;56;75;80;81;81 14:13:59 INFO - PID 1658 | |9;json-stringify-tinderbox;70;71;89;96;64;59;59;59;59;59 14:13:59 INFO - PID 1658 | |10;stanford-crypto-aes;90;77;107;76;88;84;83;103;75;94 14:13:59 INFO - PID 1658 | |11;stanford-crypto-ccm;157;186;139;135;145;166;172;154;269;198 14:13:59 INFO - PID 1658 | |12;stanford-crypto-pbkdf2;202;200;208;187;200;244;257;208;206;233 14:13:59 INFO - PID 1658 | |13;stanford-crypto-sha256-iterative;63;98;69;85;110;101;77;91;96;109 14:13:59 INFO - PID 1658 | __end_tp_report 14:13:59 INFO - PID 1658 | __start_cc_report 14:13:59 INFO - PID 1658 | _x_x_mozilla_cycle_collect,1017 14:13:59 INFO - PID 1658 | __end_cc_report 14:13:59 INFO - PID 1658 | __startTimestamp1491772439518__endTimestamp 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | ------- Summary: start ------- 14:13:59 INFO - PID 1658 | Number of tests: 14 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#0] ai-astar Cycles:10 Average:122.20 Median:123.00 stddev:3.19 (2.6%) stddev-sans-first:1.45 14:13:59 INFO - PID 1658 | Values: 114.0 122.0 124.0 124.0 123.0 123.0 126.0 123.0 122.0 121.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#1] audio-beat-detection Cycles:10 Average:132.80 Median:139.50 stddev:16.01 (11.5%) stddev-sans-first:16.98 14:13:59 INFO - PID 1658 | Values: 132.0 116.0 117.0 141.0 103.0 144.0 151.0 151.0 135.0 138.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#2] audio-dft Cycles:10 Average:221.50 Median:222.50 stddev:10.23 (4.6%) stddev-sans-first:10.66 14:13:59 INFO - PID 1658 | Values: 216.0 223.0 225.0 222.0 221.0 230.0 244.0 212.0 209.0 213.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#3] audio-fft Cycles:10 Average:69.30 Median:68.50 stddev:11.12 (16.2%) stddev-sans-first:11.34 14:13:59 INFO - PID 1658 | Values: 78.0 61.0 63.0 87.0 60.0 64.0 60.0 60.0 73.0 87.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#4] audio-oscillator Cycles:10 Average:86.70 Median:79.50 stddev:16.11 (20.3%) stddev-sans-first:17.09 14:13:59 INFO - PID 1658 | Values: 86.0 71.0 88.0 72.0 73.0 94.0 121.0 103.0 73.0 86.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:92.90 Median:92.00 stddev:2.23 (2.4%) stddev-sans-first:2.35 14:13:59 INFO - PID 1658 | Values: 92.0 92.0 98.0 92.0 96.0 92.0 91.0 92.0 92.0 92.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#6] imaging-darkroom Cycles:10 Average:115.80 Median:103.00 stddev:23.31 (22.6%) stddev-sans-first:19.08 14:13:59 INFO - PID 1658 | Values: 158.0 101.0 103.0 103.0 103.0 103.0 131.0 100.0 101.0 155.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#7] imaging-desaturate Cycles:10 Average:112.70 Median:103.50 stddev:21.93 (21.2%) stddev-sans-first:22.92 14:13:59 INFO - PID 1658 | Values: 102.0 102.0 106.0 162.0 145.0 104.0 101.0 100.0 103.0 102.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#8] json-parse-financial Cycles:10 Average:64.00 Median:65.50 stddev:13.27 (20.3%) stddev-sans-first:13.47 14:13:59 INFO - PID 1658 | Values: 53.0 54.0 53.0 55.0 52.0 56.0 75.0 80.0 81.0 81.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:68.50 Median:67.00 stddev:13.57 (20.2%) stddev-sans-first:14.38 14:13:59 INFO - PID 1658 | Values: 70.0 71.0 89.0 96.0 64.0 59.0 59.0 59.0 59.0 59.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#10] stanford-crypto-aes Cycles:10 Average:87.70 Median:83.50 stddev:11.06 (13.2%) stddev-sans-first:11.70 14:13:59 INFO - PID 1658 | Values: 90.0 77.0 107.0 76.0 88.0 84.0 83.0 103.0 75.0 94.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:172.10 Median:169.00 stddev:39.51 (23.4%) stddev-sans-first:41.53 14:13:59 INFO - PID 1658 | Values: 157.0 186.0 139.0 135.0 145.0 166.0 172.0 154.0 269.0 198.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:214.50 Median:208.00 stddev:22.38 (10.8%) stddev-sans-first:23.28 14:13:59 INFO - PID 1658 | Values: 202.0 200.0 208.0 187.0 200.0 244.0 257.0 208.0 206.0 233.0 14:13:59 INFO - PID 1658 | 14:13:59 INFO - PID 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:89.90 Median:81.00 stddev:16.15 (19.9%) stddev-sans-first:13.89 14:13:59 INFO - PID 1658 | Values: 63.0 98.0 69.0 85.0 110.0 101.0 77.0 91.0 96.0 109.0 14:13:59 INFO - PID 1658 | -------- Summary: end -------- 14:13:59 INFO - PID 1658 | 14:14:00 INFO - TEST-INFO | 1658: exit 0 14:14:00 INFO - TEST-OK | kraken | took 63792ms 14:14:00 INFO - SUITE-END | took 1180s 14:14:01 INFO - Completed test suite (00:19:41) 14:14:01 INFO - javascript benchmark 14:14:01 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 8008.482598915346, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3050.954235686465, 3586.28274345131, 3587.2287458196483, 3608.8917332480037, 3655.945160822588, 6156.784512542062, 6220.595661282019, 6259.655718935457, 6286.49708023357, 6329.019002054689, 6153.907691384622, 6200.255969283692, 6241.282252540947, 6295.401936816007, 6310.463610593094, 5543.168524721284, 5581.134924086773, 5644.266245388094, 5654.886902261952, 5689.573282003846, 5488.945110548883, 5562.3047119110115, 5686.260786097801, 5697.116946873241, 5746.367899531048, 5501.312335958005, 5538.307711536058, 5587.3015873015875, 5600.215969764236, 5628.662280263197, 5512.366077901052, 5557.499825015728, 5577.637453565546, 5580.748866301026, 5594.804181853657, 5397.379301380352, 5505.944940550583, 5508.20131080995, 5521.061419558685, 5537.889242215173, 6132.080177995968, 6200.844978875519, 6204.47261982732, 6230.96884515579, 6248.593841400295, 6794.048833163361, 6805.48958828085, 6814.318568143196, 6821.658917054177, 6830.112085428863, 6830.31696830313, 6880.483963702693, 6886.208086070116, 6899.241083480813, 6918.377346038881, 6194.783182588588, 6205.875882482325, 6261.467775239064, 6352.428281454599, 6354.618722876642, 7027.648617569203, 7044.788656340318, 7056.0, 7115.92884071163, 7129.82175445618, 6416.679166041773, 6540.6729663516635, 6543.9345606544275, 6735.562188457735, 6738.393544581011, 6284.874302513924, 6571.817072926919, 6890.931090689029, 6896.7586134485055, 6901.413379862667, 6528.151340325728, 6782.69477873487, 6809.0, 6809.557378770364, 6842.281560436182, 5728.484436400744, 5789.826305210766, 6621.702023408958, 6712.731490740316, 6772.661366931634, 5416.891662166735, 5437.401885792559, 5441.782328706887, 5443.564514838803, 5447.618666693294, 4199.887029937069, 4784.545468180518, 4787.736674482867, 4807.062622788592, 4859.902801943941, 5315.946840531623, 5981.491573216325, 6006.12911127884, 6207.472364849038, 6422.0, 6873.5875847448315, 6928.203256625553, 6931.133608298963, 6934.4452443804375, 6947.0, 5448.645838020516, 6365.554411191172, 6430.935690643128, 6443.226812782496, 6467.579607325509, 5858.443447872445, 5915.37888521699, 5918.911216331759, 5921.970390148022, 6068.878622427526, 6260.248770147611, 6745.93254067453, 6885.173779146535, 7005.2644472329675, 7029.507934444539, 5921.904447677193, 6912.239653638197, 6936.375726184668, 6937.410320122846, 6941.167059952838, 6768.830779230559, 7050.964745176241, 7089.929100708927, 7093.113360829896, 7093.751718689821, 6641.103451034049, 6720.798376048726, 6742.966285168739, 6787.457003439812, 6796.694148763218, 6260.467860231931, 6382.712777925098, 6909.963505474219, 6938.098047253825, 6944.027836102849, 6010.887985722656, 6450.322716114928, 6475.838104047437, 6598.340165983555, 6695.12963314766, 5558.416366281483, 6623.105880706235, 6674.733010679713, 6684.16447944007, 6706.161729783777, 6248.062790581449, 6366.586171898812, 6384.361563843764, 6452.645104519109, 6538.738450461929, 6672.466202703675, 6676.766313179015, 6687.765928192684, 6711.0940023096255, 6724.630145341857, 5459.0, 6476.70854811544, 6490.448311893542, 6500.5449618526245, 6519.282878883414], "value": 6159.681490205792, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [694.8992396102565, 730.5142080516454, 818.0388044048244, 1265.183956348156, 1280.3406245783417, 37157.25685486292, 37988.620113798854, 38022.80988595056, 38034.61965380346, 38166.236675266475, 38030.049248768795, 38050.23899522015, 38381.232375352476, 38425.23149537008, 38448.423273650915, 8058.476199047072, 8560.186782255676, 8794.604242809057, 8798.736037918872, 8801.29589632831, 8672.826543469128, 8699.217070463657, 8767.21095101444, 8803.031666516677, 8811.95594022029, 8755.649774009064, 8762.079981601935, 8773.25427338677, 8811.251043661296, 8859.601317940676, 8721.476711397305, 8795.428297160695, 8802.251808596213, 8804.251638610662, 8814.162654547808, 8488.618012189405, 8538.359623028236, 8556.18716221958, 8570.614322355508, 8575.699850505265, 37200.44199337012, 37325.81337093297, 37329.88010359694, 37439.43840842389, 37542.62457375418, 3614.367485689994, 3634.909127271813, 3664.670179683841, 3672.889813305605, 3675.558932928039, 30490.84754576235, 30618.234544136576, 30626.0, 30694.079177624706, 30785.076447706604, 2732.617433559303, 3208.7112159905487, 3220.806751594912, 3232.111169428426, 3237.1583388318973, 231334.0, 236191.0, 278960.99999999796, 300698.0, 303627.0, 47992.520074799504, 48030.75984620055, 48044.0, 48107.75946120247, 48272.51727482751, 50040.99918001692, 50259.24611130836, 50270.497295027315, 50389.99220015579, 50454.49545504498, 14299.64250893736, 14325.64185895361, 14459.27703614815, 14487.420503179756, 14491.85508144905, 11732.824007639892, 11793.52825886955, 11873.0, 11876.406179691154, 11888.28670279786, 13189.889617661129, 13454.93272533631, 13459.461621535225, 13491.52779652727, 13575.524856630169, 5473.863153421196, 5667.206591077253, 5898.705064746746, 5945.613535120204, 5978.372270911578, 1052.4739128263896, 1054.778496515725, 1059.1103473082494, 1081.1566977757361, 1083.6207327435493, 30139.547906781416, 30406.695933040388, 30482.0, 30561.235969100504, 30831.8458407711, 1109.1902910875106, 1109.2180013090751, 1143.1826244235413, 1144.3648774929827, 1145.478807142748, 10991.725206869893, 11647.184697071123, 12406.25562466255, 12461.065420093348, 12522.311272880079, 375279.7192308844, 379505.0, 379708.0, 380675.0, 381527.0, 51936.740316298936, 52189.21716174261, 52236.43290701285, 52414.73792631089, 52704.736476317375, 1466.1789397937189, 1496.4687535924809, 1521.6043828604643, 1525.3441020361129, 1538.277009805412, 22813.201537946094, 23815.8092095398, 23848.0, 23899.163529276393, 23930.76069239355, 37963.05092372602, 38014.80992595019, 38123.80938095292, 38427.807860960515, 38442.0, 347570.0, 348067.0, 349373.0, 350124.0, 350164.0, 32845.850395236055, 36106.09734756652, 36680.89957301178, 36691.26617467689, 36788.63211367852, 37960.62039379571, 44767.0, 46033.76983115197, 46046.0, 46174.0, 29853.5521967175, 30564.235894101937, 30999.380012400077, 31179.220519487193, 31504.684953150176, 30107.849460753434, 30220.0, 30948.53577196389, 30974.0707778767, 31242.375152496366, 37084.44373334348, 37165.81417092897, 37307.81346093252, 37314.6268537322, 37343.81328093342, 28457.857710711312, 28665.710043047697, 29940.850295748383, 31664.525032124075, 31962.840185798923, 21182.47043823855, 23854.642180366962, 24346.00419036245, 24948.37629059288, 25152.37119072038], "value": 17918.33179372275, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [400.40139990713885, 435.4883012460358, 436.4805881001607, 448.14851781614925, 453.53965724789333, 19341.806581934175, 21041.894790526047, 21527.892360538215, 21744.673829892545, 21825.23611673588, 21942.0, 22750.0, 23143.842807859583, 23203.535929281403, 23373.532529349402, 6132.356102609228, 6318.842028949267, 6370.108184854124, 6371.776987805428, 6376.330485299047, 5961.701914904259, 6203.9069413958605, 6243.063540468921, 6256.405641464054, 6258.812235632939, 6135.079738039308, 6135.447809697126, 6201.782937597185, 6207.720652570622, 6221.875562488747, 6138.815835524942, 6265.624062556216, 6292.1820163378925, 6294.307626161119, 6306.432421082101, 4456.732596044248, 4491.640668746492, 4507.57178068083, 5031.968446468465, 5589.133684278943, 27877.58183627247, 28396.716032839613, 28449.715502844912, 28477.288067798472, 28802.0, 2223.7442694090064, 2289.8740569268684, 2297.8506397084134, 2300.723913130418, 2302.861828290308, 14958.102513849204, 16847.32610695571, 17769.289228430847, 18154.727679084823, 18350.449486515427, 2144.6997420361163, 2170.848040637156, 2184.159098746978, 2209.6795964585044, 2216.4927849062633, 134347.0, 140299.0, 162348.0, 173816.0, 176183.0, 12335.876641233473, 13009.154404963647, 13025.0, 13027.413766380347, 13047.412866421033, 16607.50177494677, 16640.6671866562, 16641.916790416213, 16700.582485437717, 16714.832851671326, 8745.990924346415, 9060.728178154666, 9067.727968160834, 9140.497272649935, 9227.861582076273, 7524.548527088392, 8455.492670439793, 8626.482411055356, 8642.395032347675, 8838.0, 7791.220877912277, 7791.818290878563, 7877.448578599558, 7921.207879212033, 7956.721514746956, 1880.4922670878786, 2180.5529866377674, 2188.5841690078832, 2192.5066859956382, 2404.2426635609727, 941.6841927320376, 943.5470973932413, 973.2846357926918, 978.9461579613189, 1010.5200029985698, 19409.320673776634, 20126.39620811378, 20605.38183854487, 20681.276155334792, 20848.791512084987, 992.2161492420906, 1018.9750485581981, 1021.7445638590352, 1023.5854478936043, 1034.4362322534237, 8973.109655237911, 10400.375977441377, 10696.304740192018, 10709.78580428387, 10788.51451684676, 699911.0, 715864.0, 718612.0, 724782.3760881162, 741708.0, 153612.0, 158168.0, 160825.0, 163039.18480407522, 169501.0, 1202.296656455983, 1225.375058720059, 1269.4005553003283, 1270.7966725323904, 1273.9299338536468, 21147.577048458636, 21204.501257292373, 22215.77784222137, 22233.0, 22534.0, 49446.50553494419, 64379.03431448439, 64398.67800660967, 64432.67783661239, 64992.0, 87293.56353218193, 89571.55214223887, 90321.0, 90495.54752226197, 91000.54499727723, 7552.584607846621, 8821.0, 8850.822983540163, 8855.33584981142, 8929.508877011827, 13736.725265494833, 14006.885211698245, 14074.718505630035, 14155.858441415454, 14194.432222710975, 11588.768224635629, 11619.128565357802, 11976.341301228516, 12032.879671203176, 12139.271643701406, 11185.552577897119, 11226.775464490827, 11232.213745037769, 11348.319100853974, 11439.370834604177, 11590.71023224426, 11783.76432471363, 12112.273263604213, 12125.514979400725, 12275.0, 7619.69521219145, 8474.66101355939, 8586.871196931927, 8755.824883502422, 8888.155625215593, 9968.55141518619, 10849.294795838245, 11047.226694131334, 12031.217970831867, 12146.271223726604], "value": 10687.303861523382, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1333.2000799520285, 1467.6864206535151, 1468.1484738851468, 1480.5040311495648, 1496.543554215964, 11462.254953428032, 11952.7609447811, 12043.51825926964, 12110.697232569179, 12218.816717749241, 11969.521219151224, 12107.57623483176, 12111.0, 12142.392880355968, 12162.939185304062, 2539.5047965646704, 2579.1746641074865, 2579.2262321303533, 2582.6771653543306, 2594.07910191882, 2475.047510736681, 2541.0344069253656, 2543.313305407539, 2560.2703229579574, 2561.065211197909, 2466.7903228225623, 2532.15172917073, 2537.619357096432, 2548.987255063722, 2551.119863647039, 2456.226288719057, 2510.7614776596192, 2530.02594001309, 2538.3780973661387, 2542.9618555721495, 2441.40185654514, 2527.4060595759984, 2537.3529749913655, 2543.758342957416, 2554.246497283306, 12104.0, 12166.695832604168, 12178.208416452992, 12186.329751863645, 12189.756204875854, 6236.719347629368, 6874.759383421556, 6876.656167191621, 6878.965605171992, 6885.311468853125, 4181.540030596632, 4450.732956022649, 4460.933086003713, 4491.483479399878, 4527.7736113194205, 1263.2736176698436, 1264.4373253902017, 1269.3018839638162, 1295.7149427126112, 1318.1168617026615, 15188.0, 15868.761968570481, 16803.41188058412, 16899.577510562212, 16909.74635380458, 5564.248826408464, 5715.657060576379, 5925.288965324189, 5929.169916211734, 5942.732577034044, 12368.938155309166, 12436.067294952914, 12472.81290780639, 12482.251064936132, 12514.624561263177, 6643.236027856762, 6675.699593518303, 6676.0987266718375, 6676.399124078759, 6676.46588272937, 5964.433378829004, 6125.41808528198, 6129.754809807647, 6179.783707570214, 6194.628322300587, 4367.934480982788, 4481.5070342262325, 4512.0, 4526.59260666535, 4530.977345113253, 1297.5977446991462, 1324.6224825924758, 1328.800679898023, 1335.1121504199598, 1344.5495758920872, 809.6842231529708, 826.801567623766, 843.4348986179275, 850.7277671145174, 874.2612492444002, 6203.627782333075, 6283.120363149163, 6313.0, 6434.195725534309, 6554.508411869129, 588.2558563417281, 603.809799913026, 613.1109890658563, 614.2598169206096, 616.6608365399013, 4137.379393091061, 4356.607905288539, 4407.206702793528, 4498.167838949805, 4533.093381323749, 14666.633334166518, 15225.162616056, 15227.695446091016, 15236.771448428282, 15261.771073433907, 13446.663833404047, 15153.393864245527, 15163.545093647208, 15394.692106158038, 15395.846041539666, 10625.096866766411, 10912.945435272932, 10921.0, 10938.398388088732, 10945.781084378426, 6151.200343955257, 6310.318114801283, 7052.506324557232, 7230.819229519304, 7297.489175757858, 12594.685132871751, 13539.255340956342, 13543.0, 13682.657933551343, 13730.519431820238, 11269.887301127212, 11518.481668324777, 13496.055276130577, 13848.584542463743, 13990.300484975507, 9598.616055357987, 9683.225341972766, 9806.068423499755, 9815.950920245354, 9819.607215711292, 10968.890311096788, 10985.39580323058, 11060.557577696802, 11099.667009989713, 11104.777904442028, 9039.322050846344, 9453.95273023658, 9665.275104367342, 9719.319647624881, 9721.319507634398, 9424.717258482257, 9427.57575909072, 9497.715068547956, 9524.809503809747, 9614.326997110134, 8035.437519373588, 8163.918360816316, 8251.174882511701, 8254.298384637374, 8268.2558569729, 5174.430812610534, 6067.54493412987, 6073.696315184311, 6180.412860778219, 6312.5581209314905, 6136.325004249618, 6257.217847769029, 6299.685015749286, 6304.968475157595, 6361.554691171574], "value": 5465.647015576269, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.80929061144536, 383.23162060069575, 397.4217513517832, 401.530209654704, 406.4837656176655, 6174.9382506174925, 6356.555041147116, 6371.840703982402, 6377.553571250009, 6382.553221274513, 5769.682667453299, 6099.237595300588, 6299.842503937392, 6347.0, 6361.809145725636, 4810.374651295336, 4820.662553621248, 4820.867096232382, 4829.468758436569, 4853.830115945942, 4642.048380082075, 4713.033828065256, 4741.122892264942, 4792.0, 4803.447603525587, 4749.4063242094735, 4750.311204875308, 4751.263554149113, 4753.714777113367, 4770.4513980892125, 4554.180247555439, 4770.093682200388, 4778.661974647104, 4809.014152098812, 4814.855554333376, 4031.5363733170475, 4206.221848957922, 4215.789210539491, 4225.830966761357, 4252.362840306473, 5068.898622027576, 6038.396160383971, 6095.817125486198, 6138.877222455526, 6162.383761623802, 4702.412198475191, 5393.946060539383, 5403.621746477748, 5683.8010669626365, 5717.9714101429645, 6538.0, 6696.06255124283, 6697.698603562802, 6703.731850725965, 6706.798796036127, 4065.6989763275765, 4554.2485489894125, 4809.254565542346, 4809.5430934061205, 4809.831655892062, 6753.932460675429, 6985.266547012592, 7047.92952070483, 7062.788116356517, 7176.497645164841, 4647.814087436464, 4665.580097791215, 4677.0, 4689.273162659793, 4698.483166851644, 3841.193349396602, 4682.859514214579, 4791.0, 4796.616270698336, 4802.351682522884, 4921.0157968406465, 5300.761465734051, 5461.1808228766, 5550.3626430203185, 5569.888602227933, 6097.719478909478, 6168.753249869955, 6363.840903977345, 6667.666616669244, 6671.966640166768, 1488.7692407676827, 1531.9157446340557, 1615.2569817883905, 1720.8967461952323, 1730.9221085051204, 1038.6341960322152, 1162.511745067074, 1171.5882361754066, 1182.3024415594841, 1184.9407529623484, 6218.3673163492585, 6226.6575338355915, 6237.625742455467, 6257.0, 6257.217847769029, 6305.716242768995, 6793.932060679429, 6836.794896153124, 6845.178578570504, 6870.3473170048765, 5580.098348858571, 5850.12248162779, 5931.58478906481, 6239.688015599202, 6251.78118765841, 3574.7318951078255, 3599.8380072896784, 3612.7290453216115, 3615.6565126312958, 3619.819009049537, 5731.398203188689, 6797.014432907201, 6881.587104773629, 7066.7173313066905, 7095.0, 5466.64466809664, 6376.53339731855, 6779.3220677932795, 7018.368346848808, 7075.620254050411, 5809.680467574324, 5861.062230043172, 6296.748130074838, 6802.455803535705, 7041.0, 6287.46556542699, 6832.453403727591, 6858.348456896587, 6891.27641597625, 6896.517243793087, 5545.973994810973, 5844.013035567928, 6019.0, 6423.261324947504, 6735.922252439586, 5711.657300561979, 6273.874522509433, 7085.50401471892, 7109.653814241391, 7121.216666166614, 4362.454693163355, 4456.264716321771, 4458.974435879831, 4478.081993191323, 4535.387722657399, 5741.540676745767, 6912.792616221522, 6913.170419549686, 6913.896291555731, 6945.930540694529, 6441.1626488556185, 6507.893658078233, 6550.574212676161, 6681.766138185335, 6729.798106056826, 6451.806445806634, 6456.322086180883, 6629.7679581214425, 6665.733370665119, 6696.0, 5453.836384908459, 6518.282988871315, 6522.902156467562, 6536.607803531803, 6549.967250163719, 5110.61670374716, 5804.200697783701, 6137.7851775187655, 6576.881930071995, 6631.834204144935, 5370.4629537045985, 5612.691301978431, 6489.448396886129, 6495.253045899593, 6511.692970229248], "value": 4885.713200349477, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [834.6160766047625, 842.3261390887288, 860.4106187261722, 883.7348795361391, 885.3890815337417, 13503.662408439794, 14384.640383990405, 14990.475333363334, 16544.17279136043, 16889.746653800186, 16654.66690666186, 16985.660286794257, 17256.807490614246, 17399.956002639818, 17455.0, 953.8950715421298, 968.7142293023539, 970.6457143142773, 971.504532688327, 972.2465089555616, 971.7813491964322, 994.0606127209753, 995.1640621877622, 996.9880571220217, 998.3610489286863, 1049.459528342904, 1051.6634676903393, 1052.7578656908915, 1054.662507997441, 1056.8097742406364, 1031.0771859186061, 1045.947702614866, 1046.7435478307825, 1048.1771809129796, 1055.2033214922722, 777.0985656638329, 779.9805004874867, 782.12402109637, 782.2451334462271, 785.9371250299962, 15303.005304655162, 16638.83361166385, 16759.497215083567, 17079.402220922333, 17313.567160820956, 16772.664546709, 17131.0, 17188.484345469657, 17375.826241737548, 17457.738133928, 13938.512152074629, 14001.71996560063, 14144.29278536069, 14149.292535373294, 14328.570142895729, 3514.947275790865, 3528.7177025838128, 3551.5738111426535, 3560.0, 3564.465330200491, 102923.99999999926, 103065.4846725769, 103220.96779032264, 103458.0, 104376.0, 13293.069485135948, 13313.134646248156, 13422.798658020138, 13455.327233638474, 13507.662308442368, 23830.642540361907, 24908.0, 25029.37426564314, 25316.620250696255, 25984.480310393687, 7673.426947475754, 7938.9206107939335, 8557.828843423096, 8892.777680558038, 8952.820943581091, 4398.560143985575, 4806.423229212517, 4827.106985207748, 4907.33750943618, 4920.630952678492, 4547.954520454819, 4752.120857641347, 4754.405699287589, 4795.904081918342, 4802.447718512346, 924.2421214604094, 955.6894009446958, 959.0792838874759, 966.4587830814767, 967.3905439573024, 670.7954074007474, 680.4080450008524, 683.2279524137692, 691.2810676895972, 702.2766550453042, 10311.91724868893, 10606.575736970435, 11248.212625116163, 11478.0, 12435.93782031084, 645.096864389858, 670.4669787518934, 677.7695583501535, 691.1153723234269, 692.1071817355655, 4905.2151655734915, 5486.451354864482, 5491.203775452537, 5580.86047848807, 5831.94168058314, 104337.0, 105955.0, 106423.46788266009, 106488.0, 109337.45331273293, 93190.0, 94042.0, 94695.57956630657, 96000.55999160018, 96919.51540242395, 65828.0, 67283.32716672869, 67952.3204767946, 68000.65999670069, 69205.26986825268, 11862.58480953197, 12084.395780210778, 12612.495500180257, 12616.87383126157, 12629.684257893627, 34611.653883460844, 34779.478307824895, 35572.28855422825, 36624.63375366212, 36963.0, 46771.532284676716, 47527.0, 47761.28358074562, 66761.33238667551, 67670.0, 11448.828267575826, 15355.309011094305, 16368.67262654764, 17008.979461232364, 17125.57186070358, 17951.551211219823, 18220.90889545544, 18514.629707405507, 18597.81402185961, 18786.718199227296, 16001.679966400374, 16694.582635433726, 16857.662846743242, 17039.062851543284, 17693.496052835653, 16866.409675661784, 17351.913240433718, 17389.0, 17403.0, 17587.736183956997, 15229.771553426486, 16719.24763385675, 16883.91558042202, 16961.406350777663, 17197.968121912727, 11767.23512971654, 11916.940415297868, 11998.880011199777, 12099.33453660057, 12119.091068170099, 12423.875761242272, 12808.551700690432, 12875.356232188165, 12918.806217906927, 13085.542006030124], "value": 8374.862334984657, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1650.6000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 122.0, 124.0, 124.0, 123.0, 123.0, 126.0, 123.0, 122.0, 121.0], "value": 122.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 116.0, 117.0, 141.0, 103.0, 144.0, 151.0, 151.0, 135.0, 138.0], "value": 132.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [216.0, 223.0, 225.0, 222.0, 221.0, 230.0, 244.0, 212.0, 209.0, 213.0], "value": 221.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 61.0, 63.0, 87.0, 60.0, 64.0, 60.0, 60.0, 73.0, 87.0], "value": 69.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 88.0, 72.0, 73.0, 94.0, 121.0, 103.0, 73.0, 86.0], "value": 86.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 92.0, 98.0, 92.0, 96.0, 92.0, 91.0, 92.0, 92.0, 92.0], "value": 92.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 101.0, 103.0, 103.0, 103.0, 103.0, 131.0, 100.0, 101.0, 155.0], "value": 115.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 102.0, 106.0, 162.0, 145.0, 104.0, 101.0, 100.0, 103.0, 102.0], "value": 112.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 54.0, 53.0, 55.0, 52.0, 56.0, 75.0, 80.0, 81.0, 81.0], "value": 64.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 89.0, 96.0, 64.0, 59.0, 59.0, 59.0, 59.0, 59.0], "value": 68.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 77.0, 107.0, 76.0, 88.0, 84.0, 83.0, 103.0, 75.0, 94.0], "value": 87.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 186.0, 139.0, 135.0, 145.0, 166.0, 172.0, 154.0, 269.0, 198.0], "value": 172.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 200.0, 208.0, 187.0, 200.0, 244.0, 257.0, 208.0, 206.0, 233.0], "value": 214.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 98.0, 69.0, 85.0, 110.0, 101.0, 77.0, 91.0, 96.0, 109.0], "value": 89.9, "unit": "score"}]}]} 14:14:01 INFO - Return code: 0 14:14:01 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:14:01 ERROR - # TBPL SUCCESS # 14:14:01 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:14:01 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:14:01 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:14:01 INFO - 2017-04-09 14:14:01.305 screenresolution[1663:10734] starting screenresolution argv=screenresolution get 14:14:01 INFO - 2017-04-09 14:14:01.308 screenresolution[1663:10734] Display 0: 1600x1200x32@60 14:14:01 INFO - 2017-04-09 14:14:01.314 screenresolution[1664:10737] starting screenresolution argv=screenresolution list 14:14:01 INFO - Available Modes on Display 0 14:14:01 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:14:01 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:14:01 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:14:01 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:14:01 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:14:01 INFO - Intel Iris: 14:14:01 INFO - Chipset Model: Intel Iris 14:14:01 INFO - Type: GPU 14:14:01 INFO - Bus: Built-In 14:14:01 INFO - VRAM (Dynamic, Max): 1536 MB 14:14:01 INFO - Vendor: Intel (0x8086) 14:14:01 INFO - Device ID: 0x0a2e 14:14:01 INFO - Revision ID: 0x0009 14:14:01 INFO - Displays: 14:14:01 INFO - Display: 14:14:01 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:14:01 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:14:01 INFO - Display Serial Number: 1600x1200 60 14:14:01 INFO - Main Display: Yes 14:14:01 INFO - Mirror: Off 14:14:01 INFO - Online: Yes 14:14:01 INFO - Rotation: Supported 14:14:01 INFO - Return code: 0 14:14:01 INFO - Running post-action listener: _resource_record_post_action 14:14:01 INFO - [mozharness: 2017-04-09 21:14:01.551864Z] Finished run-tests step (success) 14:14:01 INFO - Running post-run listener: _resource_record_post_run 14:14:01 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 39655424; Write bytes: 522043904; Read time: 254; Write time: 1640 14:14:01 INFO - TinderboxPrint: CPU usage
54.3% 14:14:01 INFO - TinderboxPrint: I/O read bytes / time
39,655,424 / 254 14:14:01 INFO - TinderboxPrint: I/O write bytes / time
522,043,904 / 1,640 14:14:01 INFO - TinderboxPrint: CPU idle
2,197.3 (45.7%) 14:14:01 INFO - TinderboxPrint: CPU system
969.6 (20.2%) 14:14:01 INFO - TinderboxPrint: CPU user
1,642.8 (34.2%) 14:14:01 INFO - TinderboxPrint: Swap in / out
247,308,288 / 0 14:14:01 INFO - install - Wall time: 21s; CPU: 55.0%; Read bytes: 200319488; Write bytes: 193967616; Read time: 15838; Write time: 405 14:14:01 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 33402880; Write bytes: 311229440; Read time: 200; Write time: 1184 14:14:02 INFO - Running post-run listener: _upload_blobber_files 14:14:02 INFO - Blob upload gear active. 14:14:02 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:14:02 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 14:14:02 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:14:02 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:14:02 INFO - (blobuploader) - INFO - Open directory for files ... 14:14:02 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:14:02 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:02 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:14:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:03 INFO - (blobuploader) - INFO - Done attempting. 14:14:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:14:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:03 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:14:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:03 INFO - (blobuploader) - INFO - Done attempting. 14:14:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:14:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:04 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:14:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:04 INFO - (blobuploader) - INFO - Done attempting. 14:14:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:14:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:14:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:14:07 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:14:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:14:07 INFO - (blobuploader) - INFO - Done attempting. 14:14:07 INFO - (blobuploader) - INFO - Iteration through files over. 14:14:07 INFO - Return code: 0 14:14:07 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:14:07 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:14:07 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef7b9bfc55fa44eb2aedfd1e3f58ccb90438864a89a8f9bcb5ad887b623a5c32c39d58f7f76639749d53c889a1bbff105ae0a357eff00ab21f758350b02d335d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de6e141b8d32fe690ada19fb128ee9c4c68659d2786344af54bb7c1ab3c84c2951f35502472a2a4712ecffbbdcea2934d039f737413c48d770a3216736fc625a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b40e582d71d1e5ff66eb2a324276f7808635a790592b4fd1f18b950d93b5c7c5c71b6f1d698084cdb0075d502e39222937090057e6a1651350d824950c69fb39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/840daf294e356c52cc829de895d02bd2118b0e76b305d0924e368d0db2bb5005194f9d51fcc51ea459fe6ae30af6b097a9fcdb70db44982b79974060d3909ac4"} 14:14:07 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:14:07 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:14:07 INFO - Contents: 14:14:07 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef7b9bfc55fa44eb2aedfd1e3f58ccb90438864a89a8f9bcb5ad887b623a5c32c39d58f7f76639749d53c889a1bbff105ae0a357eff00ab21f758350b02d335d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de6e141b8d32fe690ada19fb128ee9c4c68659d2786344af54bb7c1ab3c84c2951f35502472a2a4712ecffbbdcea2934d039f737413c48d770a3216736fc625a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b40e582d71d1e5ff66eb2a324276f7808635a790592b4fd1f18b950d93b5c7c5c71b6f1d698084cdb0075d502e39222937090057e6a1651350d824950c69fb39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/840daf294e356c52cc829de895d02bd2118b0e76b305d0924e368d0db2bb5005194f9d51fcc51ea459fe6ae30af6b097a9fcdb70db44982b79974060d3909ac4"} 14:14:07 INFO - Running post-run listener: copy_logs_to_upload_dir 14:14:07 INFO - Copying logs to upload dir... 14:14:07 INFO - mkdir: /builds/slave/test/build/upload/logs 14:14:07 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.577750 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-09 14:14:07.254939) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 14:14:07.259029) ========= 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.X0MI16m5TB/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.rl6XGodboW/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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/Firefox/sha512/ef7b9bfc55fa44eb2aedfd1e3f58ccb90438864a89a8f9bcb5ad887b623a5c32c39d58f7f76639749d53c889a1bbff105ae0a357eff00ab21f758350b02d335d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de6e141b8d32fe690ada19fb128ee9c4c68659d2786344af54bb7c1ab3c84c2951f35502472a2a4712ecffbbdcea2934d039f737413c48d770a3216736fc625a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b40e582d71d1e5ff66eb2a324276f7808635a790592b4fd1f18b950d93b5c7c5c71b6f1d698084cdb0075d502e39222937090057e6a1651350d824950c69fb39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/840daf294e356c52cc829de895d02bd2118b0e76b305d0924e368d0db2bb5005194f9d51fcc51ea459fe6ae30af6b097a9fcdb70db44982b79974060d3909ac4"} build_url:https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011223 build_url: 'https://queue.taskcluster.net/v1/task/F6Sq3l9KTze9sqnfvtI9eQ/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/ef7b9bfc55fa44eb2aedfd1e3f58ccb90438864a89a8f9bcb5ad887b623a5c32c39d58f7f76639749d53c889a1bbff105ae0a357eff00ab21f758350b02d335d", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/de6e141b8d32fe690ada19fb128ee9c4c68659d2786344af54bb7c1ab3c84c2951f35502472a2a4712ecffbbdcea2934d039f737413c48d770a3216736fc625a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b40e582d71d1e5ff66eb2a324276f7808635a790592b4fd1f18b950d93b5c7c5c71b6f1d698084cdb0075d502e39222937090057e6a1651350d824950c69fb39", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/840daf294e356c52cc829de895d02bd2118b0e76b305d0924e368d0db2bb5005194f9d51fcc51ea459fe6ae30af6b097a9fcdb70db44982b79974060d3909ac4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-09 14:14:07.297320) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 14:14:07.297717) ========= 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.X0MI16m5TB/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.rl6XGodboW/Listeners TMPDIR=/var/folders/94/wsdgpk794y1563fy307h6kq400000w/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.005152 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-09 14:14:07.346722) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 14:14:07.347040) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-09 14:14:07.347402) ========= ========= Total master_lag: 0.19 =========