builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0290 starttime: 1492133983.89 results: success (0) buildid: 20170413134823 builduid: 34578bd8592d478392c58a9c3b24747f revision: 22d0169c276164d03abb48e8c330499ee6197eaf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:43.891284) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:43.891888) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:43.892305) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:43.956629) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:43.957111) ========= 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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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-13 18:39:43-- 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% 45.9M=0s 2017-04-13 18:39:44 (45.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.149261 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:44.132169) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:44.132638) ========= 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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.037239 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:44.192251) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 18:39:44.192611) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 22d0169c276164d03abb48e8c330499ee6197eaf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 22d0169c276164d03abb48e8c330499ee6197eaf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LPuxt7z2jS/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.JVy9EeAwKs/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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-13 18:39:44,299 truncating revision to first 12 chars 2017-04-13 18:39:44,299 Setting DEBUG logging. 2017-04-13 18:39:44,299 attempt 1/10 2017-04-13 18:39:44,299 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/22d0169c2761?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 18:39:45,551 unpacking tar archive at: pine-22d0169c2761/testing/mozharness/ program finished with exit code 0 elapsedTime=1.529586 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-13 18:39:45.745607) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:45.746101) ========= script_repo_revision: 22d0169c276164d03abb48e8c330499ee6197eaf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:45.746625) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:45.746899) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 18:39:45.766839) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-13 18:39:45.767192) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 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', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LPuxt7z2jS/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.JVy9EeAwKs/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 18:39:45 INFO - MultiFileLogger online at 20170413 18:39:45 in /builds/slave/test 18:39:45 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 18:39:45 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:39:45 INFO - {'append_to_log': False, 18:39:45 INFO - 'base_work_dir': '/builds/slave/test', 18:39:45 INFO - 'blob_upload_branch': 'Pine', 18:39:45 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:39:45 INFO - 'branch': 'Pine', 18:39:45 INFO - 'buildbot_json_path': 'buildprops.json', 18:39:45 INFO - 'config_files': ('talos/mac_config.py',), 18:39:45 INFO - 'default_actions': ('clobber', 18:39:45 INFO - 'read-buildbot-config', 18:39:45 INFO - 'download-and-extract', 18:39:45 INFO - 'populate-webroot', 18:39:45 INFO - 'create-virtualenv', 18:39:45 INFO - 'install', 18:39:45 INFO - 'run-tests'), 18:39:45 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:39:45 INFO - 'download_minidump_stackwalk': True, 18:39:45 INFO - 'download_symbols': 'ondemand', 18:39:45 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:39:45 INFO - 'tooltool.py': '/tools/tooltool.py', 18:39:45 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:39:45 INFO - '/tools/misc-python/virtualenv.py')}, 18:39:45 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:39:45 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:39:45 INFO - 'gecko_profile': False, 18:39:45 INFO - 'gecko_profile_interval': 0, 18:39:45 INFO - 'installer_path': 'installer.exe', 18:39:45 INFO - 'log_level': 'info', 18:39:45 INFO - 'log_name': 'talos', 18:39:45 INFO - 'log_to_console': True, 18:39:45 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:39:45 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:39:45 INFO - 'opt_config_files': (), 18:39:45 INFO - 'pip_index': False, 18:39:45 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:39:45 INFO - 'cmd': ('bash', 18:39:45 INFO - '-c', 18:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:39:45 INFO - 'enabled': True, 18:39:45 INFO - 'halt_on_failure': False, 18:39:45 INFO - 'name': 'check_screen_resolution'},), 18:39:45 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:39:45 INFO - 'cmd': ('bash', 18:39:45 INFO - '-c', 18:39:45 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:39:45 INFO - 'enabled': True, 18:39:45 INFO - 'halt_on_failure': False, 18:39:45 INFO - 'name': 'check_screen_resolution'},), 18:39:45 INFO - 'run_cmd_checks_enabled': True, 18:39:45 INFO - 'suite': 'dromaeojs', 18:39:45 INFO - 'system_bits': '32', 18:39:45 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:39:45 INFO - 'title': 't-yosemite-r7-0290', 18:39:45 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:39:45 INFO - 'use_talos_json': True, 18:39:45 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:39:45 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:39:45 INFO - 'work_dir': 'build'} 18:39:45 INFO - [mozharness: 2017-04-14 01:39:45.982513Z] Running clobber step. 18:39:45 INFO - Running pre-action listener: _resource_record_pre_action 18:39:45 INFO - Running main action method: clobber 18:39:45 INFO - rmtree: /builds/slave/test/build 18:39:45 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:39:48 INFO - Running post-action listener: _resource_record_post_action 18:39:48 INFO - [mozharness: 2017-04-14 01:39:48.392573Z] Finished clobber step (success) 18:39:48 INFO - [mozharness: 2017-04-14 01:39:48.392712Z] Running read-buildbot-config step. 18:39:48 INFO - Running pre-action listener: _resource_record_pre_action 18:39:48 INFO - Running main action method: read_buildbot_config 18:39:48 INFO - Using buildbot properties: 18:39:48 INFO - { 18:39:48 INFO - "project": "", 18:39:48 INFO - "product": "firefox", 18:39:48 INFO - "script_repo_revision": "default", 18:39:48 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 18:39:48 INFO - "repository": "", 18:39:48 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 18:39:48 INFO - "buildid": "20170413134823", 18:39:48 INFO - "pgo_build": "False", 18:39:48 INFO - "basedir": "/builds/slave/test", 18:39:48 INFO - "buildnumber": 0, 18:39:48 INFO - "slavename": "t-yosemite-r7-0290", 18:39:48 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 18:39:48 INFO - "platform": "yosemite_r7", 18:39:48 INFO - "scheduler": "tests-pine-macosx64-talos", 18:39:48 INFO - "branch": "pine", 18:39:48 INFO - "repo_path": "projects/pine", 18:39:48 INFO - "revision": "22d0169c276164d03abb48e8c330499ee6197eaf", 18:39:48 INFO - "stage_platform": "macosx64", 18:39:48 INFO - "builduid": "34578bd8592d478392c58a9c3b24747f", 18:39:48 INFO - "slavebuilddir": "test" 18:39:48 INFO - } 18:39:48 INFO - Found installer url https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:39:48 INFO - Running post-action listener: _resource_record_post_action 18:39:48 INFO - [mozharness: 2017-04-14 01:39:48.396029Z] Finished read-buildbot-config step (success) 18:39:48 INFO - [mozharness: 2017-04-14 01:39:48.396123Z] Running download-and-extract step. 18:39:48 INFO - Running pre-action listener: _resource_record_pre_action 18:39:48 INFO - Running main action method: download_and_extract 18:39:48 INFO - mkdir: /builds/slave/test/build/tests 18:39: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')]} 18:39:48 INFO - trying https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:39:48 INFO - Downloading https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/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 18:39:48 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/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 18:39:49 INFO - Downloaded 1325 bytes. 18:39:49 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:39:49 INFO - Using the following test package requirements: 18:39:49 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:39:49 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:39:49 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:39:49 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:39:49 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'jsshell-mac.zip'], 18:39:49 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:39:49 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:39:49 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:39:49 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:39:49 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:39:49 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:39:49 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:39:49 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:39:49 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:39:49 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:39:49 INFO - Fetch https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:39:50 INFO - Expected file size: 52594875 18:39:50 INFO - Obtained file size: 52594875 18:40:07 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 18:40:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:40:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:40:07 INFO - Fetch https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:40:09 INFO - Expected file size: 52594875 18:40:09 INFO - Obtained file size: 52594875 18:40:24 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:40:24 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:40:24 INFO - Fetch https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:40:25 INFO - Expected file size: 14408853 18:40:25 INFO - Obtained file size: 14408853 18:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:26 INFO - trying https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:40:26 INFO - Downloading https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:40:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/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 18:40:28 INFO - Downloaded 71148382 bytes. 18:40:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:40:28 INFO - mkdir: /builds/slave/test/properties 18:40:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:40:28 INFO - Writing to file /builds/slave/test/properties/build_url 18:40:28 INFO - Contents: 18:40:28 INFO - build_url:https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:40:28 INFO - Running post-action listener: _resource_record_post_action 18:40:28 INFO - Running post-action listener: set_extra_try_arguments 18:40:28 INFO - [mozharness: 2017-04-14 01:40:28.747723Z] Finished download-and-extract step (success) 18:40:28 INFO - [mozharness: 2017-04-14 01:40:28.747849Z] Running populate-webroot step. 18:40:28 INFO - Running pre-action listener: _resource_record_pre_action 18:40:28 INFO - Running main action method: populate_webroot 18:40:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:40:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:40:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:40:28 INFO - '--tppagecycles', 18:40:28 INFO - '7'], 18:40:28 INFO - 'tests': ['tsvgm']}}, 18:40:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:40:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:40:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:40:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:40:28 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:28 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:40:28 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['damp', 'tps']}, 18:40:28 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'tests': ['damp', 'tps']}, 18:40:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['dromaeo_dom']}, 18:40:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:40:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:40:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:40:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['a11yr', 18:40:28 INFO - 'ts_paint', 18:40:28 INFO - 'tpaint', 18:40:28 INFO - 'sessionrestore', 18:40:28 INFO - 'sessionrestore_no_auto_restore', 18:40:28 INFO - 'tabpaint']}, 18:40:28 INFO - 'other-e10s': {'tests': ['a11yr', 18:40:28 INFO - 'ts_paint', 18:40:28 INFO - 'tpaint', 18:40:28 INFO - 'sessionrestore', 18:40:28 INFO - 'sessionrestore_no_auto_restore', 18:40:28 INFO - 'tabpaint']}, 18:40:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:40:28 INFO - 'ts_paint', 18:40:28 INFO - 'tpaint', 18:40:28 INFO - 'sessionrestore', 18:40:28 INFO - 'sessionrestore_no_auto_restore', 18:40:28 INFO - 'tabpaint']}, 18:40:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:40:28 INFO - 'ts_paint', 18:40:28 INFO - 'tpaint', 18:40:28 INFO - 'sessionrestore', 18:40:28 INFO - 'sessionrestore_no_auto_restore', 18:40:28 INFO - 'tabpaint']}, 18:40:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['a11yr', 18:40:28 INFO - 'ts_paint', 18:40:28 INFO - 'tpaint', 18:40:28 INFO - 'sessionrestore', 18:40:28 INFO - 'sessionrestore_no_auto_restore', 18:40:28 INFO - 'tabpaint']}, 18:40:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['a11yr', 18:40:28 INFO - 'ts_paint', 18:40:28 INFO - 'tpaint', 18:40:28 INFO - 'sessionrestore', 18:40:28 INFO - 'sessionrestore_no_auto_restore', 18:40:28 INFO - 'tabpaint']}, 18:40:28 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:40:28 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:40:28 INFO - 'bloom_basic_ref']}, 18:40:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['tsvgx', 18:40:28 INFO - 'tsvgr_opacity', 18:40:28 INFO - 'tart', 18:40:28 INFO - 'tscrollx', 18:40:28 INFO - 'cart', 18:40:28 INFO - 'tsvg_static']}, 18:40:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:40:28 INFO - 'tsvgr_opacity', 18:40:28 INFO - 'tart', 18:40:28 INFO - 'tscrollx', 18:40:28 INFO - 'cart', 18:40:28 INFO - 'tsvg_static']}, 18:40:28 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'talos_options': ['--disable-e10s'], 18:40:28 INFO - 'tests': ['tp5o']}, 18:40:28 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:40:28 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'talos_options': ['--disable-e10s', 18:40:28 INFO - '--xperf_path', 18:40:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:28 INFO - 'tests': ['tp5n']}, 18:40:28 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:40:28 INFO - 'talos_options': ['--xperf_path', 18:40:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:40:28 INFO - 'tests': ['tp5n']}}, 18:40:28 INFO - 'talos.zip': {'path': '', 18:40:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:40:28 INFO - Running post-action listener: _resource_record_post_action 18:40:28 INFO - [mozharness: 2017-04-14 01:40:28.767101Z] Finished populate-webroot step (success) 18:40:28 INFO - [mozharness: 2017-04-14 01:40:28.767212Z] Running create-virtualenv step. 18:40:28 INFO - Running pre-action listener: _resource_record_pre_action 18:40:28 INFO - Running main action method: create_virtualenv 18:40:28 INFO - Creating virtualenv /builds/slave/test/build/venv 18:40:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 18:40:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:40:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:40:28 INFO - Using real prefix '/tools/python27' 18:40:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:40:29 INFO - Installing distribute.............................................................................................................................................................................................done. 18:40:32 INFO - Installing pip.................done. 18:40:32 INFO - Return code: 0 18:40:32 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:40:32 INFO - Reading from file tmpfile_stdout 18:40:32 INFO - Output received: 18:40:32 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 18:40:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:40:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:40:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:32 INFO - 'HOME': '/Users/cltbld', 18:40:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:32 INFO - 'LOGNAME': 'cltbld', 18:40:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:32 INFO - 'MOZ_NO_REMOTE': '1', 18:40:32 INFO - 'NO_EM_RESTART': '1', 18:40:32 INFO - 'PAGER': '/bin/cat', 18:40:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:32 INFO - 'PWD': '/builds/slave/test', 18:40:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:32 INFO - 'SHELL': '/bin/bash', 18:40:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:32 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:32 INFO - 'USER': 'cltbld', 18:40:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:32 INFO - 'XPC_FLAGS': '0x0', 18:40:32 INFO - 'XPC_SERVICE_NAME': '0', 18:40:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 18:40:32 INFO - Cleaning up... 18:40:33 INFO - Return code: 0 18:40:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:33 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:40:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:40:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:33 INFO - 'HOME': '/Users/cltbld', 18:40:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:33 INFO - 'LOGNAME': 'cltbld', 18:40:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:33 INFO - 'MOZ_NO_REMOTE': '1', 18:40:33 INFO - 'NO_EM_RESTART': '1', 18:40:33 INFO - 'PAGER': '/bin/cat', 18:40:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:33 INFO - 'PWD': '/builds/slave/test', 18:40:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:33 INFO - 'SHELL': '/bin/bash', 18:40:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:33 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:33 INFO - 'USER': 'cltbld', 18:40:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:33 INFO - 'XPC_FLAGS': '0x0', 18:40:33 INFO - 'XPC_SERVICE_NAME': '0', 18:40:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:33 INFO - Downloading/unpacking psutil>=3.1.1 18:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:40:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:36 INFO - Installing collected packages: psutil 18:40:36 INFO - Running setup.py install for psutil 18:40:36 INFO - building 'psutil._psutil_osx' extension 18:40:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 18:40:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 18:40:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 18:40:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 18:40:37 INFO - building 'psutil._psutil_posix' extension 18:40:37 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 18:40:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:40:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:40:37 INFO - ^ 18:40:37 INFO - 1 warning generated. 18:40:37 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 18:40:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:40:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:40:37 INFO - Successfully installed psutil 18:40:37 INFO - Cleaning up... 18:40:37 INFO - Return code: 0 18:40:37 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:37 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:40:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:40:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:37 INFO - 'HOME': '/Users/cltbld', 18:40:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:37 INFO - 'LOGNAME': 'cltbld', 18:40:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:37 INFO - 'MOZ_NO_REMOTE': '1', 18:40:37 INFO - 'NO_EM_RESTART': '1', 18:40:37 INFO - 'PAGER': '/bin/cat', 18:40:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:37 INFO - 'PWD': '/builds/slave/test', 18:40:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:37 INFO - 'SHELL': '/bin/bash', 18:40:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:37 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:37 INFO - 'USER': 'cltbld', 18:40:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:37 INFO - 'XPC_FLAGS': '0x0', 18:40:37 INFO - 'XPC_SERVICE_NAME': '0', 18:40:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:40:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:40:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:40:40 INFO - Installing collected packages: mozsystemmonitor 18:40:40 INFO - Running setup.py install for mozsystemmonitor 18:40:41 INFO - Successfully installed mozsystemmonitor 18:40:41 INFO - Cleaning up... 18:40:41 INFO - Return code: 0 18:40:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:41 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:40:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:40:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:41 INFO - 'HOME': '/Users/cltbld', 18:40:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:41 INFO - 'LOGNAME': 'cltbld', 18:40:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:41 INFO - 'MOZ_NO_REMOTE': '1', 18:40:41 INFO - 'NO_EM_RESTART': '1', 18:40:41 INFO - 'PAGER': '/bin/cat', 18:40:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:41 INFO - 'PWD': '/builds/slave/test', 18:40:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:41 INFO - 'SHELL': '/bin/bash', 18:40:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:41 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:41 INFO - 'USER': 'cltbld', 18:40:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:41 INFO - 'XPC_FLAGS': '0x0', 18:40:41 INFO - 'XPC_SERVICE_NAME': '0', 18:40:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:42 INFO - Downloading/unpacking jsonschema==2.5.1 18:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:45 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:40:45 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40:45 INFO - Downloading functools32-3.2.3-2.tar.gz 18:40:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:40:45 INFO - warning: no files found matching '*.txt' 18:40:45 INFO - no previously-included directories found matching 'build' 18:40:45 INFO - no previously-included directories found matching 'dist' 18:40:45 INFO - no previously-included directories found matching '.git*' 18:40:45 INFO - Installing collected packages: jsonschema, functools32 18:40:45 INFO - Running setup.py install for functools32 18:40:45 INFO - warning: no files found matching '*.txt' 18:40:45 INFO - no previously-included directories found matching 'build' 18:40:45 INFO - no previously-included directories found matching 'dist' 18:40:45 INFO - no previously-included directories found matching '.git*' 18:40:45 INFO - Successfully installed jsonschema functools32 18:40:45 INFO - Cleaning up... 18:40:46 INFO - Return code: 0 18:40:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:46 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 18:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 18:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:46 INFO - 'HOME': '/Users/cltbld', 18:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:46 INFO - 'LOGNAME': 'cltbld', 18:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:46 INFO - 'MOZ_NO_REMOTE': '1', 18:40:46 INFO - 'NO_EM_RESTART': '1', 18:40:46 INFO - 'PAGER': '/bin/cat', 18:40:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:46 INFO - 'PWD': '/builds/slave/test', 18:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:46 INFO - 'SHELL': '/bin/bash', 18:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:46 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:46 INFO - 'USER': 'cltbld', 18:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:46 INFO - 'XPC_FLAGS': '0x0', 18:40:46 INFO - 'XPC_SERVICE_NAME': '0', 18:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:46 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:40:46 INFO - Cleaning up... 18:40:46 INFO - Return code: 0 18:40:46 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:46 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 18:40:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 18:40:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:46 INFO - 'HOME': '/Users/cltbld', 18:40:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:46 INFO - 'LOGNAME': 'cltbld', 18:40:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:46 INFO - 'MOZ_NO_REMOTE': '1', 18:40:46 INFO - 'NO_EM_RESTART': '1', 18:40:46 INFO - 'PAGER': '/bin/cat', 18:40:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:46 INFO - 'PWD': '/builds/slave/test', 18:40:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:46 INFO - 'SHELL': '/bin/bash', 18:40:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:46 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:46 INFO - 'USER': 'cltbld', 18:40:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:46 INFO - 'XPC_FLAGS': '0x0', 18:40:46 INFO - 'XPC_SERVICE_NAME': '0', 18:40:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:46 INFO - Downloading/unpacking blobuploader==1.2.4 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40: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 18:40:49 INFO - Downloading blobuploader-1.2.4.tar.gz 18:40:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:40:49 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:40:49 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:50 INFO - Downloading docopt-0.6.1.tar.gz 18:40:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:40:50 INFO - Installing collected packages: blobuploader, requests, docopt 18:40:50 INFO - Running setup.py install for blobuploader 18:40:50 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:40:50 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:40:50 INFO - Running setup.py install for requests 18:40:51 INFO - Running setup.py install for docopt 18:40:51 INFO - Successfully installed blobuploader requests docopt 18:40:51 INFO - Cleaning up... 18:40:51 INFO - Return code: 0 18:40:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:51 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:40:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:40:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:51 INFO - 'HOME': '/Users/cltbld', 18:40:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:51 INFO - 'LOGNAME': 'cltbld', 18:40:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:51 INFO - 'MOZ_NO_REMOTE': '1', 18:40:51 INFO - 'NO_EM_RESTART': '1', 18:40:51 INFO - 'PAGER': '/bin/cat', 18:40:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:51 INFO - 'PWD': '/builds/slave/test', 18:40:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:51 INFO - 'SHELL': '/bin/bash', 18:40:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:51 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:51 INFO - 'USER': 'cltbld', 18:40:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:51 INFO - 'XPC_FLAGS': '0x0', 18:40:51 INFO - 'XPC_SERVICE_NAME': '0', 18:40:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:40:51 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-MYbhKw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:40:51 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-N4Fl7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:40:51 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-4YMXuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:40:51 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-FdW6my-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-PZEMY0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Fdxtf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-aDCQXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-MCi5Xs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-7UhMUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-stdgOT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-FVXlEu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-gDX78T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-5CyF4L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:40:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:40:52 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-RTY28n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-8042mI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:40:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-TJaHpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:40:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:40:53 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-KIfBSH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:40:53 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:40:53 INFO - Running setup.py install for manifestparser 18:40:53 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:40:53 INFO - Running setup.py install for mozcrash 18:40:53 INFO - Running setup.py install for mozdebug 18:40:53 INFO - Running setup.py install for mozdevice 18:40:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:40:53 INFO - Running setup.py install for mozfile 18:40:54 INFO - Running setup.py install for mozhttpd 18:40:54 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Running setup.py install for mozinfo 18:40:54 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Running setup.py install for mozInstall 18:40:54 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Running setup.py install for mozleak 18:40:54 INFO - Running setup.py install for mozlog 18:40:54 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Running setup.py install for moznetwork 18:40:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:40:54 INFO - Running setup.py install for mozprocess 18:40:55 INFO - Running setup.py install for mozprofile 18:40:55 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:40:55 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:40:55 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:40:55 INFO - Running setup.py install for mozrunner 18:40:55 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:40:55 INFO - Running setup.py install for mozscreenshot 18:40:55 INFO - Running setup.py install for moztest 18:40:55 INFO - Running setup.py install for mozversion 18:40:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:40:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:40:55 INFO - Cleaning up... 18:40:55 INFO - Return code: 0 18:40:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:40:55 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 18:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:40:55 INFO - 'HOME': '/Users/cltbld', 18:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:40:55 INFO - 'LOGNAME': 'cltbld', 18:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:40:55 INFO - 'MOZ_NO_REMOTE': '1', 18:40:55 INFO - 'NO_EM_RESTART': '1', 18:40:55 INFO - 'PAGER': '/bin/cat', 18:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:40:55 INFO - 'PWD': '/builds/slave/test', 18:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:40:55 INFO - 'SHELL': '/bin/bash', 18:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:40:55 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:40:55 INFO - 'USER': 'cltbld', 18:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:40:55 INFO - 'XPC_FLAGS': '0x0', 18:40:55 INFO - 'XPC_SERVICE_NAME': '0', 18:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:40:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-3NhYRc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-8Cj2sW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:40:56 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)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-LG4lDt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-j3GUU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:40:56 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)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-nPzCjb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Sj0rhD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EX2Zys-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 18:40:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:40:56 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-suVcwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-rYDnLQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-zwQwTk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-QhCDgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Ip9NtG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:40:57 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)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-2RI8T_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-BdakQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:40:57 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)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-ht1QL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-CpAdi7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:40:57 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)) 18:40:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:40:57 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-b8mjkf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 18:40:57 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)) 18:40:57 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)) 18:40:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 18:40:57 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)) 18:40:57 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)) 18:40:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:40:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:40:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:41:00 INFO - Downloading blessings-1.6.tar.gz 18:41:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:41:00 INFO - Installing collected packages: blessings 18:41:00 INFO - Running setup.py install for blessings 18:41:00 INFO - Successfully installed blessings 18:41:00 INFO - Cleaning up... 18:41:01 INFO - Return code: 0 18:41:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:41:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:41:01 INFO - Reading from file tmpfile_stdout 18:41:01 INFO - Current package versions: 18:41:01 INFO - blessings == 1.6 18:41:01 INFO - blobuploader == 1.2.4 18:41:01 INFO - docopt == 0.6.1 18:41:01 INFO - functools32 == 3.2.3-2 18:41:01 INFO - jsonschema == 2.5.1 18:41:01 INFO - manifestparser == 1.1 18:41:01 INFO - mozInstall == 1.12 18:41:01 INFO - mozcrash == 1.0 18:41:01 INFO - mozdebug == 0.1 18:41:01 INFO - mozdevice == 0.50 18:41:01 INFO - mozfile == 1.2 18:41:01 INFO - mozhttpd == 0.7 18:41:01 INFO - mozinfo == 0.9 18:41:01 INFO - mozleak == 0.1 18:41:01 INFO - mozlog == 3.4 18:41:01 INFO - moznetwork == 0.27 18:41:01 INFO - mozprocess == 0.25 18:41:01 INFO - mozprofile == 0.28 18:41:01 INFO - mozrunner == 6.13 18:41:01 INFO - mozscreenshot == 0.1 18:41:01 INFO - mozsystemmonitor == 0.3 18:41:01 INFO - moztest == 0.8 18:41:01 INFO - mozversion == 1.4 18:41:01 INFO - psutil == 3.1.1 18:41:01 INFO - requests == 1.2.3 18:41:01 INFO - wsgiref == 0.1.2 18:41:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:01 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:41:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 18:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 18:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:41:01 INFO - 'HOME': '/Users/cltbld', 18:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:41:01 INFO - 'LOGNAME': 'cltbld', 18:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:01 INFO - 'MOZ_NO_REMOTE': '1', 18:41:01 INFO - 'NO_EM_RESTART': '1', 18:41:01 INFO - 'PAGER': '/bin/cat', 18:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:01 INFO - 'PWD': '/builds/slave/test', 18:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:41:01 INFO - 'SHELL': '/bin/bash', 18:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:41:01 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:41:01 INFO - 'USER': 'cltbld', 18:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:01 INFO - 'XPC_FLAGS': '0x0', 18:41:01 INFO - 'XPC_SERVICE_NAME': '0', 18:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 18:41:01 INFO - Cleaning up... 18:41:01 INFO - Return code: 0 18:41:01 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:41:01 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 0x10c75fb70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c762768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa0f346abc0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, '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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 18:41:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 18:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 18:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:41:01 INFO - 'HOME': '/Users/cltbld', 18:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:41:01 INFO - 'LOGNAME': 'cltbld', 18:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:01 INFO - 'MOZ_NO_REMOTE': '1', 18:41:01 INFO - 'NO_EM_RESTART': '1', 18:41:01 INFO - 'PAGER': '/bin/cat', 18:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:01 INFO - 'PWD': '/builds/slave/test', 18:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:41:01 INFO - 'SHELL': '/bin/bash', 18:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:41:01 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:41:01 INFO - 'USER': 'cltbld', 18:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:01 INFO - 'XPC_FLAGS': '0x0', 18:41:01 INFO - 'XPC_SERVICE_NAME': '0', 18:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:41:01 INFO - Cleaning up... 18:41:01 INFO - Return code: 0 18:41:01 INFO - Running post-action listener: _resource_record_post_action 18:41:01 INFO - Running post-action listener: _start_resource_monitoring 18:41:01 INFO - Starting resource monitoring. 18:41:01 INFO - [mozharness: 2017-04-14 01:41:01.851240Z] Finished create-virtualenv step (success) 18:41:01 INFO - [mozharness: 2017-04-14 01:41:01.852152Z] Running install step. 18:41:01 INFO - Running pre-action listener: _resource_record_pre_action 18:41:01 INFO - Running main action method: install 18:41:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:41:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:41:02 INFO - Reading from file tmpfile_stdout 18:41:02 INFO - Detecting whether we're running mozinstall >=1.0... 18:41:02 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:41:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:41:02 INFO - Reading from file tmpfile_stdout 18:41:02 INFO - Output received: 18:41:02 INFO - Usage: mozinstall [options] installer 18:41:02 INFO - Options: 18:41:02 INFO - -h, --help show this help message and exit 18:41:02 INFO - -d DEST, --destination=DEST 18:41:02 INFO - Directory to install application into. [default: 18:41:02 INFO - "/builds/slave/test"] 18:41:02 INFO - --app=APP Application being installed. [default: firefox] 18:41:02 INFO - mkdir: /builds/slave/test/build/application 18:41:02 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'] 18:41:02 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 18:41:21 INFO - Reading from file tmpfile_stdout 18:41:21 INFO - Output received: 18:41:21 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:41:21 INFO - Running post-action listener: _resource_record_post_action 18:41:21 INFO - [mozharness: 2017-04-14 01:41:21.652022Z] Finished install step (success) 18:41:21 INFO - [mozharness: 2017-04-14 01:41:21.652188Z] Running run-tests step. 18:41:21 INFO - Running pre-action listener: _resource_record_pre_action 18:41:21 INFO - Running main action method: run_tests 18:41:21 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:41:21 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:41:21 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:41:21 INFO - 2017-04-13 18:41:21.666 screenresolution[1623:7545] starting screenresolution argv=screenresolution get 18:41:21 INFO - 2017-04-13 18:41:21.669 screenresolution[1623:7545] Display 0: 1600x1200x32@60 18:41:21 INFO - 2017-04-13 18:41:21.675 screenresolution[1624:7548] starting screenresolution argv=screenresolution list 18:41:21 INFO - Available Modes on Display 0 18:41:21 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:41:21 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:41:21 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:41:21 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:41:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:41:21 INFO - Intel Iris: 18:41:21 INFO - Chipset Model: Intel Iris 18:41:21 INFO - Type: GPU 18:41:21 INFO - Bus: Built-In 18:41:21 INFO - VRAM (Dynamic, Max): 1536 MB 18:41:21 INFO - Vendor: Intel (0x8086) 18:41:21 INFO - Device ID: 0x0a2e 18:41:21 INFO - Revision ID: 0x0009 18:41:21 INFO - Displays: 18:41:21 INFO - Display: 18:41:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:41:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:41:21 INFO - Display Serial Number: 1600x1200 60 18:41:21 INFO - Main Display: Yes 18:41:21 INFO - Mirror: Off 18:41:21 INFO - Online: Yes 18:41:21 INFO - Rotation: Supported 18:41:21 INFO - Return code: 0 18:41:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:41:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:41:21 INFO - Python 2.7.3 18:41:21 INFO - Return code: 0 18:41:21 INFO - grabbing minidump binary from tooltool 18:41:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:41:21 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 0x10c53dd30>, '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 0x10c464db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c532be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:41:21 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 18:41:21 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 18:41:21 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 18:41:21 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:41:22 INFO - Return code: 0 18:41:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:41:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:41:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:41:22 INFO - ENV: RUST_BACKTRACE is now 1 18:41:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:41:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:41:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 18:41:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0290 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 18:41:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LPuxt7z2jS/Render', 18:41:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:41:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:41:22 INFO - 'HOME': '/Users/cltbld', 18:41:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:41:22 INFO - 'LOGNAME': 'cltbld', 18:41:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:41:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:41:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:41:22 INFO - 'MOZ_NO_REMOTE': '1', 18:41:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:41:22 INFO - 'NO_EM_RESTART': '1', 18:41:22 INFO - 'PAGER': '/bin/cat', 18:41:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:41:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:41:22 INFO - 'PWD': '/builds/slave/test', 18:41:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:41:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:41:22 INFO - 'RUST_BACKTRACE': '1', 18:41:22 INFO - 'SHELL': '/bin/bash', 18:41:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.JVy9EeAwKs/Listeners', 18:41:22 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 18:41:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:41:22 INFO - 'USER': 'cltbld', 18:41:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:41:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:41:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:41:22 INFO - 'XPC_FLAGS': '0x0', 18:41:22 INFO - 'XPC_SERVICE_NAME': '0', 18:41:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:41:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 18:41:22 INFO - mozversion application_buildid: 20170413134823 18:41:22 INFO - mozversion application_changeset: 22d0169c276164d03abb48e8c330499ee6197eaf 18:41:22 INFO - mozversion application_display_name: Nightly 18:41:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:41:22 INFO - mozversion application_name: Firefox 18:41:22 INFO - mozversion application_remotingname: firefox 18:41:22 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 18:41:22 INFO - mozversion application_vendor: Mozilla 18:41:22 INFO - mozversion application_version: 55.0a1 18:41:22 INFO - mozversion platform_buildid: 20170413134823 18:41:22 INFO - mozversion platform_changeset: 22d0169c276164d03abb48e8c330499ee6197eaf 18:41:22 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 18:41:22 INFO - mozversion platform_version: 55.0a1 18:41:22 INFO - using testdate: 1492134082 18:41:22 INFO - actual date: 1492134082 18:41:22 INFO - starting webserver on 'localhost:49241' 18:41:22 INFO - SUITE-START | Running 2 tests 18:41:22 INFO - TEST-START | dromaeo_css 18:41:22 INFO - operating with platform_type : mac_ 18:41:22 INFO - Initialising browser for dromaeo_css test... 18:41:22 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpOfp6z3/profile http://localhost:49241/getInfo.html) 18:41:25 INFO - PID 1634 | __metrics Screen width/height:1600/1200 18:41:25 INFO - PID 1634 | colorDepth:24 18:41:25 INFO - PID 1634 | Browser inner width/height: 1024/689 18:41:25 INFO - PID 1634 | __metrics 18:41:25 INFO - PID 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 18:41:25 INFO - PID 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:41:26 INFO - TEST-INFO | 1634: exit 0 18:41:26 INFO - Browser initialized. 18:41:26 INFO - Running cycle 1/1 for dromaeo_css test... 18:41:26 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpOfp6z3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:41:38 INFO - PID 1636 | RSS: Main: 240975872 18:41:38 INFO - PID 1636 | 18:44:42 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 18:44:44 INFO - PID 1636 | RSS: Main: 442335232 18:44:44 INFO - PID 1636 | 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 18:46:27 INFO - PID 1636 | 18:46:27 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:46:27 INFO - PID 1636 | Full stack: 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 18:46:27 INFO - PID 1636 | 18:46:27 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:46:27 INFO - PID 1636 | Full stack: 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 18:46:27 INFO - PID 1636 | 18:46:27 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:46:27 INFO - PID 1636 | Full stack: 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | ************************* 18:46:27 INFO - PID 1636 | A coding exception was thrown and uncaught in a Task. 18:46:27 INFO - PID 1636 | 18:46:27 ERROR - PID 1636 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:46:27 INFO - PID 1636 | Full stack: 18:46:27 INFO - PID 1636 | ************************* 18:47:47 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 18:47:48 INFO - PID 1636 | RSS: Main: 373084160 18:47:48 INFO - PID 1636 | 18:50:50 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 18:50:51 INFO - PID 1636 | RSS: Main: 328806400 18:50:51 INFO - PID 1636 | 18:53:53 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 18:53:54 INFO - PID 1636 | RSS: Main: 333688832 18:53:54 INFO - PID 1636 | 18:56:57 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 18:56:58 INFO - PID 1636 | RSS: Main: 343904256 18:56:58 INFO - PID 1636 | 19:00:00 INFO - PID 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 19:00:01 INFO - PID 1636 | RSS: Main: 380567552 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | __start_tp_report 19:00:01 INFO - PID 1636 | _x_x_mozilla_page_load 19:00:01 INFO - PID 1636 | _x_x_mozilla_page_load_details 19:00:01 INFO - PID 1636 | |i|pagename|runs| 19:00:01 INFO - PID 1636 | |0;dojo.html;3425.5889293284777;3427.160345715299;3468.1156305142185;3475.843587038583;3492.755507114503;4927.876803079925;5823.67969761663;5949.9587572174805;6089.573729838913;6108.328083910772;5856.063029915214;5926.9407305926925;5932.466078052975;5934.139549765281;5934.525237980962;5140.100482415572;5167.224916262572;5186.92219616706;5224.268602395668;5228.816991405282;5254.369475662907;5282.973585132069;5284.392294886099;5287.656302340336;5310.4424035476295;5272.209168624718;5278.102722537178;5297.44376840432;5298.47015298469;5304.761285742132;5284.365876094893;5286.15421532553;5290.947090529084;5299.231611416361;5302.946970530284;4960.156773348539;4976.402831660224;5216.347956505437;5216.400113986902;5302.628815982883;5764.423557644244;6126;6127.234095738033;6159.87680246397;6186;6283.088952101965;6375.553711240215;6380.330065343165;6405.5516113872045;6406.03909413587;6320.93679063208;6321.620702757803;6394.328595497453;6437.871242575169;6461.773837915697;6184.907226391608;6223.626582405025;6233.376662333731;6237.251529816361;6242.1261023456755;6630.569013014138;6632.602043877383;6661.40047395727;6665.333466653393;6677.732890684415;6242.157308763259;6273.466755325757;6316.905246421307;6326.304106548276;6344.555881088372;6361.968190159112;6400.199975003125;6427.839304017343;6440.452561532228;6448.838779030561;6298.338674439209;6334.588251763621;6352.7776527822225;6356.841078973062;6365.649889256136;6240.4071613196675;6261.5616906816085;6362.236531616143;6372.171617689671;6387.808365749035;5130.538251557377;5162.767675454538;5193.818216362409;5248.160294352885;5268.1834315681135;4647.09381670571;4652.302154676758;4656.254999200111;4681.29780532916;4700.858974230779;6092.45167934888;6137.355577664371;6140.692965351804;6146.78486252979;6148.907766383508;6248.875022499525;6320.304766475684;6410.358964103646;6449.097126402307;6473.579217350857;6091.878162436727;6120.456967560027;6126.151269885628;6134.6625935573975;6142.938570614237;5648.63283886546;5653.180288858092;5654.434556544313;5658.688772117573;5668.093105103246;6387.361263873576;6396.859705348972;6430.549861509649;6472.126262954441;6496.675166241764;6329.651869147241;6332.176817013758;6338.429541341117;6351.2696039956;6461.450776684034;6678.83302917431;6723.630200339028;6729.528932974645;6729.798106056826;6743.52795304324;6337.429631333202;6373.808785736435;6377.649229292248;6387.233531976192;6388.009858471944;6244.438000579969;6320.146780184617;6335.208098987627;6445.484361251182;6476.255230648536;6186.845328866634;6277.089821975879;6280.152179455715;6285.842853928689;6294.307626161027;4887;6216.627002379872;6228.283747369111;6270.529710271839;6278.717457714505;6257.405546473078;6271.96864015677;6281.623102613858;6287.905681414691;6314.400131987454;6256.843578910563;6269.216347956505;6288.5598008138995;6289.7169627365965;6295.905561416674;6071.423214794588;6127.678242056309;6134.528431832502;6154.907676384768;6193.38066193377 19:00:01 INFO - PID 1636 | |1;ext.html;799.1688643810437;818.1981657975184;1137.5677242647794;1155.3010428690648;1157.5659127827064;36810.631893681086;37179.62820371799;37319.62680373195;37361.06597335061;37394.439083413774;39163;39357.40963885544;39419.40870886939;39455;39549.60450395495;6116.7553297868035;6279.780207692709;8573.271271941892;8664.783380415476;8680.86978695317;8454.830903381928;8636.481811091355;8646.308295336357;8663.740087797376;8664.696735614254;8683.305335573139;8744.300455963508;8748.431351962103;8753.824923501526;8760.299176065899;8695.043545210021;8723.738287851374;8725.563721813884;8736.301095912311;8747.518886461243;8535.274501667365;8598.785030374229;8602.784930376729;8609.784755381103;8637.568121593895;37655.4351684725;37697.62302376969;37824.81087594572;37840.81079594584;37912.43131353032;3616.764910280823;3652.7808331500196;3653.561572611277;3659.3596120678776;3662.066173125836;30650.23374415635;30674.693253067406;30721.38557228843;30730.23174420635;30799;3138.3837323778043;3161.4309424303615;3183.761217908666;3210.0369889033195;3214.951775723366;264287;270122;270263.64868175535;270385;270981.6450917752;39572;40193.59806401899;47263;48586.75706621444;48736;49498.50501495011;49784.50215497871;49824.75087624539;49852.75073624609;50000.24999625009;14022.719545609234;14171.716565668836;14191.78712319316;14223.07550009246;14223.359948802121;11626.069914406828;11629;11630.88369116315;11639.418029098511;11666.824997625043;12417.006639469002;12417.379131043412;12428.502859885684;12437.315947622968;12572;4288.541895755458;4339.717918335298;4864.294677271776;5166.509181627739;5233.685978841206;903.4940433357341;1019.2050200843354;1022.8261195596766;1060.9946950265353;1075.2204651627508;24101.879490602787;24119.397015074414;24148.396290092536;24258.878705606712;24462.143824966042;973.9366941148803;1105.9336439813476;1107.4296737180198;1150.6663067710438;1150.9079273658087;9206.434906065984;11076.833847492295;11850.11124165691;12399.566015189424;12435.316057616918;343537;344727;345483;345690;346428;52357;52487.475125249024;52521.737391312796;52625.42123736294;52697.73651131796;1466.288849907794;1493.193675415307;1529.694061187767;1534.884883633732;1548.6515534004984;22955.081796727944;23608.29175124749;23612.409689757205;23704.762952370256;23704.881475592512;30838.458077096504;33007.66992330046;38063.80968095252;38076.23847523089;38124.2375152501;307030;307500;307894;308486;310073;29571.704282956896;29973.55039674363;31885;34127.31745365128;34177;31004.844975774977;42223;42716;42907.35638965481;43080.78459607682;28256.85871570629;28961.56557651679;30195;30204;30517.23706907345;22044;27607;28110.87556497717;29690.851545742134;29965.55396129473;35895.10262243465;36152.819235903655;36244.81877590595;36266.27467450583;36312.45531317085;29111;29509;30108.096757097323;30478.695213047587;30531.694683052887;18759.437216883514;23702.170424035077;23742.525149497258;23778;23890.402739930945 19:00:01 INFO - PID 1636 | |2;jquery.html;372.2722078336851;400.143692498054;434.5784588948721;443.28852192231466;459.4050704337884;15386.384544618215;21398.786012139873;22044.448888777788;22963.311100666964;23152.30543083706;22312;22780;23054.423639408982;23162.65256021161;23546;6220.875582488359;6283.717232724538;6319;6400.9039864402075;6439.291677915426;3749.212665340282;6091.177691011701;6336.081268216106;6366.713497892606;6374.490040796725;3526.453399723034;3946.9802650986703;3950.940735888964;5983.700814959257;6300.149479820235;6061.939380606225;6276.744651069805;6286.497080233616;6305.4009869062365;6309.211348581451;5542.0855558832745;5566.109422492423;5607.990561698893;5610.69141197234;5622.072358060915;27801.721982780116;27905.441891162063;28110.859445702845;28147.296317592223;28163.85918070417;2200.702905107806;2247.8651280923195;2270.648049552322;2273.1930164791593;2279.7036385269976;17293.481195564153;18424.907875460536;18492.89042657445;18520.073996300132;18602.34891778795;1993.063260267672;2046.4576887124924;2084.7081408602808;2113.619548481273;2180.531185795046;143216;143778;144464;145799;167044;11118.833217501744;11203.775924481628;11642.94178529102;12704.237745735285;12713.364331783374;15632.2965466552;15692.607684807743;16352.836471635132;16816.915915420588;16866.987980721195;8960.955195223982;9049.81900361989;9125;9270.629174833066;9282.675106371245;8669.349798765117;8698.21716045559;8709.390342675952;8842;8888.866667;6630.403263706289;7804.648790804427;7852.921470785333;7909.327707144842;7965.522068675898;2187.846850720466;2208.1608988584235;2210.071769856664;2212.816143363293;2363;814.3770015937812;916.4776077635684;963.8409662405764;964.252704154272;989.3223142147652;20505;20662.690059649118;21465.316835395577;21573.892130539247;21610.567788644454;977.5063592885547;984.4831463481586;1001.8447140693204;1013.2350075692952;1014.320405328417;10596.735081622866;11133.498992545356;13202.867971320164;14045.08706934046;14362.281885905872;685189;686291;694123;720990;725818.3709081527;150854;156516;158002.41997580108;160448;162311;1169.502961241469;1173.318596951734;1227.0122551346074;1227.8894899459092;1234.7592219517107;21526.138954441645;22175.44561385978;22726.545469090855;22749.88625056864;22844.086236550353;57680.711596441746;62773.68613156905;63741.36258637354;64374.24155012257;64753.35246647473;73941.44256101761;79723.20276797158;88154.11845881458;91372.08627913636;92652;8746.256568191775;8834.249088827522;8842.602082906413;9092.681756138769;9229.769255768659;14596.708065838837;14649.633759156106;14816.777748333569;15111;15217.239138043275;11941.940290298493;12106.75786484283;12230.755384892074;12329.568465103679;12416.006719462603;11355.772884542428;11572.59495917639;11580.536778529102;11950.760984780081;11985.760284794082;12153.392330383622;12174.14780965324;12412.56556020535;12491.625251242478;12496.562620308245;8891.688790892287;9188.678396256098;9223.538823058954;9291.535423228675;9369.578368973276;11715.414229288672;11834.76330473403;12126.333051682243;12175.086868485076;12229.633111006684 19:00:01 INFO - PID 1636 | |3;mootools.html;1377.7588921938661;1442.4374493947362;1467.794508768772;1473.1234915225436;1476.9040012399196;11130.49912753926;11645.592404265832;11655.41722913854;11656;11675;11275.154363422735;11438.828417573743;11862.347570883598;11895.345755983417;11899.52401903925;2486.9626955595636;2539.3651587103222;2539.7079335876424;2540.314115188898;2552.119518766023;2435.5981263091658;2473.8515689058713;2507.2352932355625;2510.083819405913;2510.57320255557;2403.471236328005;2494.3390001649577;2495.226479791261;2495.9376015599573;2500.6749122614033;2476.2571228631336;2494.825362224627;2498.0757119865675;2524.6591710119083;2530.114459939016;2469.333280014404;2470.3824043989002;2496.488219914931;2500.2124330835727;2504.3113143885394;11707.824382634268;11828.704282392922;11837.053035757119;11838;11845;6456.644884531396;6702.061711360413;6712.160979877515;6712.362325579063;6730.899036514456;4009.498812648419;4229.6827737919475;4288.978555107205;4294.978525107385;4310.181065597527;1220.4020030185234;1244.3342811595753;1298.4416700818645;1300.7658621448136;1301.0632344711792;13085.88769954555;14526.418943242257;15005.399784008627;16340.83659163405;16471.588210294718;5221.086309895753;5361.115415956324;5362.91955620666;5471.8084867029465;5764.596478246566;12283.263004219596;12287.631371058882;12292.262464252173;12304.938475307566;12313.753724925451;6548.70530826114;6550.672466376662;6552;6552.868942621121;6570.047343135218;5457.7271136443815;6063.333033366326;6079.787207447718;6129.478994285536;6132.110843927606;4286.978565107154;4399;4422.137683151755;4447.488538818013;4449.377087207794;1268.4545645372577;1269.7079671675567;1270.9428075736614;1288.3365066990507;1288.6842723532607;761.2615762710162;784.7018133109382;785.921407859221;788.7594283743399;802.2378740196837;5389.784408623611;5676.517496012802;5906.877693238357;6495.577787443801;6813.931860681429;818.2472125644422;829.2081062585216;875.1205038935828;946.7103304554954;956.9808603827885;3926.6858651307825;4099.528554216244;4258.276093064186;4272.764997925082;4330.653547716175;12551.560695375618;14680.55958321252;15242.314095865713;15246.542603721906;15326.463573774865;14575.489857854924;14883.851161488463;15199.62000949963;15219.61950951213;15230.619234519225;10828.891711082788;10931.234813563133;10931.890681093088;11063.72340691473;11065.557377704963;5556.666600004012;6697.966510167418;6956.79129626112;7033.980072889504;7055.506114571931;13004.414801333773;13341.19952802835;13424.39590218423;13610.659733506742;13631.522896698569;12479.564850041997;13839.792403114163;13993.370298336802;14067.366968486238;14143.64640883945;9483.715488535356;9653.082957119062;9659.130678238993;9718.562664680248;9729.221662267144;10336.328138670962;10366.585336586451;10583.100436462986;10692.059683587077;10711.732206694895;9016.368854180144;9111.498867562348;9130.360874738704;9134.132257435533;9347.345685801929;8857.911420885708;8890.33322500828;9030.503322317336;9036.960749513628;9181.449113053239;8060.193980601893;8103.878441823495;8122.83754324922;8168.754937351889;8205.71280005197;5874.882502350015;6010.248718910137;6043.09353596964;6076.331603523698;6099.024156134997;4825.6139508839915;6184.288806787277;6229.359127450079;6230.626162430269;6249.968755155349 19:00:01 INFO - PID 1636 | |4;prototype.html;395.1543696489513;401.955784863665;403.0873505217741;409.6333781265767;409.7357204603029;5835.562332825039;5858.297004359483;5893.38119497453;5895.4988825949795;5933.673647949361;5612.102063669814;5682.403347648499;5729.942700572993;5758.3665796762425;5791.565632577563;3920.627540383659;4378.686394081778;4433.623142032931;4445.933311000337;4450.821967121311;3637.981810090946;4444.488883778358;4464.196444639963;4473.957708758043;4483.730976141425;4225.936610950838;4348.152110338469;4408.319053590256;4412.205802955466;4426.159029784347;4216.64158546524;4403.317485789692;4403.581659742319;4416.227160246976;4418.779061046935;3893.552241492236;3939.7439166454087;3955.4462375267485;3956.287868183726;4013.578574249691;5442.809501667462;5644;5695.14572814077;5705.2012718219485;5719.6854173020465;4348.239058164809;4945.837728133922;5304.787808487695;5333.919991200135;5360.865978350534;6132.386761323876;6174.691265436711;6180.2892667343385;6187;6204.81385558434;4367.388565600819;4442.600165985077;4461.687681862271;4507.2788353863225;4536.954630453686;6301.338359472235;6431.517636177212;6452.6451045192025;6511.153550038512;6531.779195073697;3544.7164226861787;3706.629337066272;3904.4338570907057;4266.573342665709;4492.573205545468;4167.124903770182;4212.7472351659;4288.378185163134;4481.5070342262325;4528.43394575678;4326.891827704332;5334.3865455473115;5361.490658387447;5362.195670649434;5363.839084827461;5127.897442051213;5152.6650767699975;6247.312795592481;6264.310925798159;6264.68676566179;1414.971700565983;1448.044290768088;1473.5726639274706;1482.5174825174824;1492.6865358274883;921.0605182713595;959.2278216036158;994.9104580587637;995.4425521707867;1001.7628229137156;5818.650880947157;5825.883482330414;5828.5337173026055;5835.883282334329;5870.178175055496;6245.221640907276;6307.873842523124;6318.273398559132;6339.841503962346;6359.141515895295;5001.8499445016705;5554.666719996813;5786.768529258782;5805.912911306334;5879.52963762898;2740.3560163361594;2848.6011958325853;3313.5692359993045;3325.9667403325657;3328.3509715605223;5760.8271751847515;6191.9690401547705;6509.218893732687;6545.378189072126;6560.507961902877;6085.883686894643;6439.238799260757;6454.580452270588;6495.902561461582;6578.947368421125;5207.5313221809465;6528.21661400635;6550.312217217123;6580.374864387875;6596.4393026592315;5159.974200128975;5933.614315069507;5935.40645935403;6345.302016778059;6358.268799088164;5472.753726082416;6244.189185136442;6286.748530058747;6293.37066293367;6326.810195694136;5737.655740655574;6370.90443643355;6404.935950640434;6433.204409398478;6500.414962653384;3358.7145092667397;3550.3076900005285;3760.680342170946;4058.8635182149046;4235.216484950294;5509.944900550943;6302.527310451643;6307.653079080695;6338.954072577973;6346.650934198663;5532.30846144232;6083.178770866053;6114.3796893824065;6163.84590385244;6182.567220294716;5293;5604.579656525859;6013.428724271187;6076.964216987359;6081.452669259788;4784.378030855966;5098.668586541863;5200.453952335101;6030.5778595497895;6033.245844269466;5113.74431278442;5919.733611987384;6127.295361033538;6131.325554189125;6153.753849845956;5742.28221472316;6018.699065046818;6032.215811944595;6035.275766907999;6036.551227705231 19:00:01 INFO - PID 1636 | |5;yui.html;723.3995783499689;863.7374556563365;875.5359659380529;887.0375642427965;892.7634176943109;13497;13883.09759865608;17767.111644417775;18041.639167216646;18518.537036574093;17566.385176518823;18067.27730890763;18161.63676726468;18312.816871831277;18408.907955460203;985.8373368394207;1009.530568285747;1011.2314640872953;1013.9847902281472;1016.0804471952895;695.9269276725947;716.7992961970657;1028.1260928211036;1032.8399098139762;1034.5965073621294;892.901780804111;1016.2784423059636;1064.2816099133092;1070.3524367757473;1078.002847366187;1058.7141471802609;1060.363781730963;1061.6284300494842;1062.3041907550567;1064.1220992680992;781.7303030454484;783.6199443270009;784.7959530522048;787.7046107709609;788.0543347982444;16423.507294781055;16699.91650041754;16908.154592270337;17187.828121718874;17499.95000299986;13659.86340136596;16725.498235052968;16843.663126737396;17085.316587336492;17248;13492.190468571818;13706.45174193031;13753.38109785062;13841.584752457342;13979.021468497209;3370.3259348130473;3398.235397035647;3398.49022646605;3398.5242066110764;3400.846961886721;88365;111927;112688;112782;113233;12132.393380330948;12191.756164876653;12196.451159697657;12231.143819932697;12271.263724176399;19834.90082549578;20669.483262918548;20669.689954650694;25146.622800658006;25291;7522.473426860177;8632.050474447931;8861.291096712248;8866.157715017063;8893.688720894866;4706.10583989041;4819.783109760069;4821.180399332122;4826.541478559531;4827.42070951488;4281.743095414286;4310.7929779661745;4386.254336762758;4417.3815665806815;4448.199324121689;947.5309721687671;971.795922856194;975.0834215837091;976.5507866381544;978.2027647467291;659.2385794407467;677.4309579952765;679.1917618034622;691.1774987764544;698.7728988078793;9998.950005250073;10904.781904362027;11774;11807.23252988553;11824.11319151067;662.4203821656051;663.8639078988796;664.7174950645956;670.8255853478131;689.1385767790263;5343.118385466432;5468.207109969032;5472.863178420572;5493.121100623961;5608.971955140199;75269;96032;102485;102843.45734813984;103284;67265;90227;90650;92349.76775812815;92554.87260781309;73850;75723.62138189384;76078.61960690272;76590;77401.45197096026;10352.637657681946;11178.776424471627;11198;11688.766224675288;12142.878571214174;39233.41149882697;39289.21421571496;39707;39750.60249397585;41862.79068604638;66716;67828;69884.10811243164;70292.59414811777;71010.64494677493;13149.408276627766;15858.92070539686;16069;16224.432144874872;16306.836931631007;17794.288228470716;18058.458246252634;18072.548186295448;18326.99201543928;18385.356512521998;16090.195490225677;16302.59243518874;16384.75422868682;16513.91743041277;16563.006219626863;16082.67834643324;16226.188690565188;16423.507294781175;16519.174041297647;16627.3349066036;12900.806487902877;15875.444359447363;15896.284667189773;16017.599560011093;16192.271347789614;11825;11894.107941904218;12077.818832717341;12090.335031573348;12133.272003679807;11043.779124417628;12923.15999460032;13018.674533136747;13064.54274100402;13213.339333033502 19:00:01 INFO - PID 1636 | __end_tp_report 19:00:01 INFO - PID 1636 | __start_cc_report 19:00:01 INFO - PID 1636 | _x_x_mozilla_cycle_collect,2127 19:00:01 INFO - PID 1636 | __end_cc_report 19:00:01 INFO - PID 1636 | __startTimestamp1492135201117__endTimestamp 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | ------- Summary: start ------- 19:00:01 INFO - PID 1636 | Number of tests: 6 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | [#0] dojo.html Cycles:165 Average:5933.20 Median:6237.25 stddev:680.66 (10.9%) stddev-sans-first:653.70 19:00:01 INFO - PID 1636 | Values: 3425.6 3427.2 3468.1 3475.8 3492.8 4927.9 5823.7 5950.0 6089.6 6108.3 5856.1 5926.9 5932.5 5934.1 5934.5 5140.1 5167.2 5186.9 5224.3 5228.8 5254.4 5283.0 5284.4 5287.7 5310.4 5272.2 5278.1 5297.4 5298.5 5304.8 5284.4 5286.2 5290.9 5299.2 5302.9 4960.2 4976.4 5216.3 5216.4 5302.6 5764.4 6126.0 6127.2 6159.9 6186.0 6283.1 6375.6 6380.3 6405.6 6406.0 6320.9 6321.6 6394.3 6437.9 6461.8 6184.9 6223.6 6233.4 6237.3 6242.1 6630.6 6632.6 6661.4 6665.3 6677.7 6242.2 6273.5 6316.9 6326.3 6344.6 6362.0 6400.2 6427.8 6440.5 6448.8 6298.3 6334.6 6352.8 6356.8 6365.6 6240.4 6261.6 6362.2 6372.2 6387.8 5130.5 5162.8 5193.8 5248.2 5268.2 4647.1 4652.3 4656.3 4681.3 4700.9 6092.5 6137.4 6140.7 6146.8 6148.9 6248.9 6320.3 6410.4 6449.1 6473.6 6091.9 6120.5 6126.2 6134.7 6142.9 5648.6 5653.2 5654.4 5658.7 5668.1 6387.4 6396.9 6430.5 6472.1 6496.7 6329.7 6332.2 6338.4 6351.3 6461.5 6678.8 6723.6 6729.5 6729.8 6743.5 6337.4 6373.8 6377.6 6387.2 6388.0 6244.4 6320.1 6335.2 6445.5 6476.3 6186.8 6277.1 6280.2 6285.8 6294.3 4887.0 6216.6 6228.3 6270.5 6278.7 6257.4 6272.0 6281.6 6287.9 6314.4 6256.8 6269.2 6288.6 6289.7 6295.9 6071.4 6127.7 6134.5 6154.9 6193.4 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | [#1] ext.html Cycles:180 Average:45133.89 Median:33567.49 stddev:81253.31 (242.1%) stddev-sans-first:81413.06 19:00:01 INFO - PID 1636 | Values: 799.2 818.2 1137.6 1155.3 1157.6 36810.6 37179.6 37319.6 37361.1 37394.4 39163.0 39357.4 39419.4 39455.0 39549.6 6116.8 6279.8 8573.3 8664.8 8680.9 8454.8 8636.5 8646.3 8663.7 8664.7 8683.3 8744.3 8748.4 8753.8 8760.3 8695.0 8723.7 8725.6 8736.3 8747.5 8535.3 8598.8 8602.8 8609.8 8637.6 37655.4 37697.6 37824.8 37840.8 37912.4 3616.8 3652.8 3653.6 3659.4 3662.1 30650.2 30674.7 30721.4 30730.2 30799.0 3138.4 3161.4 3183.8 3210.0 3215.0 264287.0 270122.0 270263.6 270385.0 270981.6 39572.0 40193.6 47263.0 48586.8 48736.0 49498.5 49784.5 49824.8 49852.8 50000.2 14022.7 14171.7 14191.8 14223.1 14223.4 11626.1 11629.0 11630.9 11639.4 11666.8 12417.0 12417.4 12428.5 12437.3 12572.0 4288.5 4339.7 4864.3 5166.5 5233.7 903.5 1019.2 1022.8 1061.0 1075.2 24101.9 24119.4 24148.4 24258.9 24462.1 973.9 1105.9 1107.4 1150.7 1150.9 9206.4 11076.8 11850.1 12399.6 12435.3 343537.0 344727.0 345483.0 345690.0 346428.0 52357.0 52487.5 52521.7 52625.4 52697.7 1466.3 1493.2 1529.7 1534.9 1548.7 22955.1 23608.3 23612.4 23704.8 23704.9 30838.5 33007.7 38063.8 38076.2 38124.2 307030.0 307500.0 307894.0 308486.0 310073.0 29571.7 29973.6 31885.0 34127.3 34177.0 31004.8 42223.0 42716.0 42907.4 43080.8 28256.9 28961.6 30195.0 30204.0 30517.2 22044.0 27607.0 28110.9 29690.9 29965.6 35895.1 36152.8 36244.8 36266.3 36312.5 29111.0 29509.0 30108.1 30478.7 30531.7 18759.4 23702.2 23742.5 23778.0 23890.4 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | [#2] jquery.html Cycles:180 Average:41029.85 Median:12561.89 stddev:117767.17 (937.5%) stddev-sans-first:118057.97 19:00:01 INFO - PID 1636 | Values: 372.3 400.1 434.6 443.3 459.4 15386.4 21398.8 22044.4 22963.3 23152.3 22312.0 22780.0 23054.4 23162.7 23546.0 6220.9 6283.7 6319.0 6400.9 6439.3 3749.2 6091.2 6336.1 6366.7 6374.5 3526.5 3947.0 3950.9 5983.7 6300.1 6061.9 6276.7 6286.5 6305.4 6309.2 5542.1 5566.1 5608.0 5610.7 5622.1 27801.7 27905.4 28110.9 28147.3 28163.9 2200.7 2247.9 2270.6 2273.2 2279.7 17293.5 18424.9 18492.9 18520.1 18602.3 1993.1 2046.5 2084.7 2113.6 2180.5 143216.0 143778.0 144464.0 145799.0 167044.0 11118.8 11203.8 11642.9 12704.2 12713.4 15632.3 15692.6 16352.8 16816.9 16867.0 8961.0 9049.8 9125.0 9270.6 9282.7 8669.3 8698.2 8709.4 8842.0 8888.9 6630.4 7804.6 7852.9 7909.3 7965.5 2187.8 2208.2 2210.1 2212.8 2363.0 814.4 916.5 963.8 964.3 989.3 20505.0 20662.7 21465.3 21573.9 21610.6 977.5 984.5 1001.8 1013.2 1014.3 10596.7 11133.5 13202.9 14045.1 14362.3 685189.0 686291.0 694123.0 720990.0 725818.4 150854.0 156516.0 158002.4 160448.0 162311.0 1169.5 1173.3 1227.0 1227.9 1234.8 21526.1 22175.4 22726.5 22749.9 22844.1 57680.7 62773.7 63741.4 64374.2 64753.4 73941.4 79723.2 88154.1 91372.1 92652.0 8746.3 8834.2 8842.6 9092.7 9229.8 14596.7 14649.6 14816.8 15111.0 15217.2 11941.9 12106.8 12230.8 12329.6 12416.0 11355.8 11572.6 11580.5 11950.8 11985.8 12153.4 12174.1 12412.6 12491.6 12496.6 8891.7 9188.7 9223.5 9291.5 9369.6 11715.4 11834.8 12126.3 12175.1 12229.6 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | [#3] mootools.html Cycles:180 Average:7135.01 Median:2534.74 stddev:4482.51 (176.8%) stddev-sans-first:4474.21 19:00:01 INFO - PID 1636 | Values: 1377.8 1442.4 1467.8 1473.1 1476.9 11130.5 11645.6 11655.4 11656.0 11675.0 11275.2 11438.8 11862.3 11895.3 11899.5 2487.0 2539.4 2539.7 2540.3 2552.1 2435.6 2473.9 2507.2 2510.1 2510.6 2403.5 2494.3 2495.2 2495.9 2500.7 2476.3 2494.8 2498.1 2524.7 2530.1 2469.3 2470.4 2496.5 2500.2 2504.3 11707.8 11828.7 11837.1 11838.0 11845.0 6456.6 6702.1 6712.2 6712.4 6730.9 4009.5 4229.7 4289.0 4295.0 4310.2 1220.4 1244.3 1298.4 1300.8 1301.1 13085.9 14526.4 15005.4 16340.8 16471.6 5221.1 5361.1 5362.9 5471.8 5764.6 12283.3 12287.6 12292.3 12304.9 12313.8 6548.7 6550.7 6552.0 6552.9 6570.0 5457.7 6063.3 6079.8 6129.5 6132.1 4287.0 4399.0 4422.1 4447.5 4449.4 1268.5 1269.7 1270.9 1288.3 1288.7 761.3 784.7 785.9 788.8 802.2 5389.8 5676.5 5906.9 6495.6 6813.9 818.2 829.2 875.1 946.7 957.0 3926.7 4099.5 4258.3 4272.8 4330.7 12551.6 14680.6 15242.3 15246.5 15326.5 14575.5 14883.9 15199.6 15219.6 15230.6 10828.9 10931.2 10931.9 11063.7 11065.6 5556.7 6698.0 6956.8 7034.0 7055.5 13004.4 13341.2 13424.4 13610.7 13631.5 12479.6 13839.8 13993.4 14067.4 14143.6 9483.7 9653.1 9659.1 9718.6 9729.2 10336.3 10366.6 10583.1 10692.1 10711.7 9016.4 9111.5 9130.4 9134.1 9347.3 8857.9 8890.3 9030.5 9037.0 9181.4 8060.2 8103.9 8122.8 8168.8 8205.7 5874.9 6010.2 6043.1 6076.3 6099.0 4825.6 6184.3 6229.4 6230.6 6250.0 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | [#4] prototype.html Cycles:180 Average:5003.81 Median:5700.17 stddev:1541.04 (27.0%) stddev-sans-first:1506.04 19:00:01 INFO - PID 1636 | Values: 395.2 402.0 403.1 409.6 409.7 5835.6 5858.3 5893.4 5895.5 5933.7 5612.1 5682.4 5729.9 5758.4 5791.6 3920.6 4378.7 4433.6 4445.9 4450.8 3638.0 4444.5 4464.2 4474.0 4483.7 4225.9 4348.2 4408.3 4412.2 4426.2 4216.6 4403.3 4403.6 4416.2 4418.8 3893.6 3939.7 3955.4 3956.3 4013.6 5442.8 5644.0 5695.1 5705.2 5719.7 4348.2 4945.8 5304.8 5333.9 5360.9 6132.4 6174.7 6180.3 6187.0 6204.8 4367.4 4442.6 4461.7 4507.3 4537.0 6301.3 6431.5 6452.6 6511.2 6531.8 3544.7 3706.6 3904.4 4266.6 4492.6 4167.1 4212.7 4288.4 4481.5 4528.4 4326.9 5334.4 5361.5 5362.2 5363.8 5127.9 5152.7 6247.3 6264.3 6264.7 1415.0 1448.0 1473.6 1482.5 1492.7 921.1 959.2 994.9 995.4 1001.8 5818.7 5825.9 5828.5 5835.9 5870.2 6245.2 6307.9 6318.3 6339.8 6359.1 5001.8 5554.7 5786.8 5805.9 5879.5 2740.4 2848.6 3313.6 3326.0 3328.4 5760.8 6192.0 6509.2 6545.4 6560.5 6085.9 6439.2 6454.6 6495.9 6578.9 5207.5 6528.2 6550.3 6580.4 6596.4 5160.0 5933.6 5935.4 6345.3 6358.3 5472.8 6244.2 6286.7 6293.4 6326.8 5737.7 6370.9 6404.9 6433.2 6500.4 3358.7 3550.3 3760.7 4058.9 4235.2 5509.9 6302.5 6307.7 6339.0 6346.7 5532.3 6083.2 6114.4 6163.8 6182.6 5293.0 5604.6 6013.4 6077.0 6081.5 4784.4 5098.7 5200.5 6030.6 6033.2 5113.7 5919.7 6127.3 6131.3 6153.8 5742.3 6018.7 6032.2 6035.3 6036.6 19:00:01 INFO - PID 1636 | 19:00:01 INFO - PID 1636 | [#5] yui.html Cycles:180 Average:20971.25 Median:18117.09 stddev:28471.81 (157.2%) stddev-sans-first:28511.08 19:00:01 INFO - PID 1636 | Values: 723.4 863.7 875.5 887.0 892.8 13497.0 13883.1 17767.1 18041.6 18518.5 17566.4 18067.3 18161.6 18312.8 18408.9 985.8 1009.5 1011.2 1014.0 1016.1 695.9 716.8 1028.1 1032.8 1034.6 892.9 1016.3 1064.3 1070.4 1078.0 1058.7 1060.4 1061.6 1062.3 1064.1 781.7 783.6 784.8 787.7 788.1 16423.5 16699.9 16908.2 17187.8 17500.0 13659.9 16725.5 16843.7 17085.3 17248.0 13492.2 13706.5 13753.4 13841.6 13979.0 3370.3 3398.2 3398.5 3398.5 3400.8 88365.0 111927.0 112688.0 112782.0 113233.0 12132.4 12191.8 12196.5 12231.1 12271.3 19834.9 20669.5 20669.7 25146.6 25291.0 7522.5 8632.1 8861.3 8866.2 8893.7 4706.1 4819.8 4821.2 4826.5 4827.4 4281.7 4310.8 4386.3 4417.4 4448.2 947.5 971.8 975.1 976.6 978.2 659.2 677.4 679.2 691.2 698.8 9999.0 10904.8 11774.0 11807.2 11824.1 662.4 663.9 664.7 670.8 689.1 5343.1 5468.2 5472.9 5493.1 5609.0 75269.0 96032.0 102485.0 102843.5 103284.0 67265.0 90227.0 90650.0 92349.8 92554.9 73850.0 75723.6 76078.6 76590.0 77401.5 10352.6 11178.8 11198.0 11688.8 12142.9 39233.4 39289.2 39707.0 39750.6 41862.8 66716.0 67828.0 69884.1 70292.6 71010.6 13149.4 15858.9 16069.0 16224.4 16306.8 17794.3 18058.5 18072.5 18327.0 18385.4 16090.2 16302.6 16384.8 16513.9 16563.0 16082.7 16226.2 16423.5 16519.2 16627.3 12900.8 15875.4 15896.3 16017.6 16192.3 11825.0 11894.1 12077.8 12090.3 12133.3 11043.8 12923.2 13018.7 13064.5 13213.3 19:00:01 INFO - PID 1636 | -------- Summary: end -------- 19:00:01 INFO - PID 1636 | 19:00:01 INFO - TEST-INFO | 1636: exit 0 19:00:01 INFO - TEST-OK | dromaeo_css | took 1119549ms 19:00:01 INFO - TEST-START | kraken 19:00:01 INFO - operating with platform_type : mac_ 19:00:01 INFO - Initialising browser for kraken test... 19:00:01 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpHCx6_8/profile http://localhost:49241/getInfo.html) 19:00:04 INFO - PID 1647 | __metrics Screen width/height:1600/1200 19:00:04 INFO - PID 1647 | colorDepth:24 19:00:04 INFO - PID 1647 | Browser inner width/height: 1024/689 19:00:04 INFO - PID 1647 | __metrics 19:00:04 INFO - PID 1647 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 19:00:04 INFO - PID 1647 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:00:05 INFO - TEST-INFO | 1647: exit 0 19:00:05 INFO - Browser initialized. 19:00:05 INFO - Running cycle 1/1 for kraken test... 19:00:05 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpHCx6_8/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:00:17 INFO - PID 1648 | RSS: Main: 240467968 19:00:17 INFO - PID 1648 | 19:00:20 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 19:00:21 INFO - PID 1648 | RSS: Main: 291348480 19:00:21 INFO - PID 1648 | 19:00:24 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 19:00:24 INFO - PID 1648 | RSS: Main: 293228544 19:00:24 INFO - PID 1648 | 19:00:28 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 19:00:28 INFO - PID 1648 | RSS: Main: 384761856 19:00:28 INFO - PID 1648 | 19:00:30 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 19:00:31 INFO - PID 1648 | RSS: Main: 288124928 19:00:31 INFO - PID 1648 | 19:00:33 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:00:34 INFO - PID 1648 | RSS: Main: 290545664 19:00:34 INFO - PID 1648 | 19:00:37 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 19:00:37 INFO - PID 1648 | RSS: Main: 331485184 19:00:37 INFO - PID 1648 | 19:00:40 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 19:00:41 INFO - PID 1648 | RSS: Main: 343830528 19:00:41 INFO - PID 1648 | 19:00:44 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 19:00:45 INFO - PID 1648 | RSS: Main: 352178176 19:00:45 INFO - PID 1648 | 19:00:46 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:00:47 INFO - PID 1648 | RSS: Main: 306987008 19:00:47 INFO - PID 1648 | 19:00:49 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:00:49 INFO - PID 1648 | RSS: Main: 311234560 19:00:49 INFO - PID 1648 | 19:00:52 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:00:52 INFO - PID 1648 | RSS: Main: 313630720 19:00:52 INFO - PID 1648 | 19:00:55 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:00:56 INFO - PID 1648 | RSS: Main: 330682368 19:00:56 INFO - PID 1648 | 19:00:59 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:01:00 INFO - PID 1648 | RSS: Main: 324624384 19:01:00 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 19:01:02 INFO - PID 1648 | RSS: Main: 386813952 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | __start_tp_report 19:01:02 INFO - PID 1648 | _x_x_mozilla_page_load 19:01:02 INFO - PID 1648 | _x_x_mozilla_page_load_details 19:01:02 INFO - PID 1648 | |i|pagename|runs| 19:01:02 INFO - PID 1648 | |0;ai-astar;111;114;113;114;114;112;115;112;113;114 19:01:02 INFO - PID 1648 | |1;audio-beat-detection;145;108;105;149;123;113;108;105;131;107 19:01:02 INFO - PID 1648 | |2;audio-dft;184;182;186;186;195;208;183;178;180;205 19:01:02 INFO - PID 1648 | |3;audio-fft;80;57;72;80;74;78;76;84;84;56 19:01:02 INFO - PID 1648 | |4;audio-oscillator;90;68;113;106;102;102;87;69;70;85 19:01:02 INFO - PID 1648 | |5;imaging-gaussian-blur;90;90;90;93;136;91;91;90;91;87 19:01:02 INFO - PID 1648 | |6;imaging-darkroom;94;93;95;94;93;95;115;116;94;93 19:01:02 INFO - PID 1648 | |7;imaging-desaturate;84;83;83;83;83;86;81;96;130;99 19:01:02 INFO - PID 1648 | |8;json-parse-financial;51;49;47;49;48;49;48;47;50;48 19:01:02 INFO - PID 1648 | |9;json-stringify-tinderbox;53;53;65;63;65;60;57;57;60;81 19:01:02 INFO - PID 1648 | |10;stanford-crypto-aes;81;99;108;106;107;83;82;83;82;83 19:01:02 INFO - PID 1648 | |11;stanford-crypto-ccm;151;141;144;143;147;143;141;155;199;166 19:01:02 INFO - PID 1648 | |12;stanford-crypto-pbkdf2;175;185;187;189;202;236;232;186;185;185 19:01:02 INFO - PID 1648 | |13;stanford-crypto-sha256-iterative;68;69;67;67;68;68;68;71;67;68 19:01:02 INFO - PID 1648 | __end_tp_report 19:01:02 INFO - PID 1648 | __start_cc_report 19:01:02 INFO - PID 1648 | _x_x_mozilla_cycle_collect,2215 19:01:02 INFO - PID 1648 | __end_cc_report 19:01:02 INFO - PID 1648 | __startTimestamp1492135262388__endTimestamp 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | ------- Summary: start ------- 19:01:02 INFO - PID 1648 | Number of tests: 14 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#0] ai-astar Cycles:10 Average:113.20 Median:114.00 stddev:1.23 (1.1%) stddev-sans-first:1.01 19:01:02 INFO - PID 1648 | Values: 111.0 114.0 113.0 114.0 114.0 112.0 115.0 112.0 113.0 114.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#1] audio-beat-detection Cycles:10 Average:119.40 Median:118.00 stddev:16.83 (14.3%) stddev-sans-first:15.08 19:01:02 INFO - PID 1648 | Values: 145.0 108.0 105.0 149.0 123.0 113.0 108.0 105.0 131.0 107.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#2] audio-dft Cycles:10 Average:188.70 Median:186.00 stddev:10.45 (5.6%) stddev-sans-first:10.94 19:01:02 INFO - PID 1648 | Values: 184.0 182.0 186.0 186.0 195.0 208.0 183.0 178.0 180.0 205.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#3] audio-fft Cycles:10 Average:74.10 Median:79.00 stddev:10.05 (12.7%) stddev-sans-first:10.43 19:01:02 INFO - PID 1648 | Values: 80.0 57.0 72.0 80.0 74.0 78.0 76.0 84.0 84.0 56.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#4] audio-oscillator Cycles:10 Average:89.20 Median:69.50 stddev:16.42 (23.6%) stddev-sans-first:17.41 19:01:02 INFO - PID 1648 | Values: 90.0 68.0 113.0 106.0 102.0 102.0 87.0 69.0 70.0 85.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#5] imaging-gaussian-blur Cycles:10 Average:94.90 Median:90.50 stddev:14.52 (16.0%) stddev-sans-first:15.29 19:01:02 INFO - PID 1648 | Values: 90.0 90.0 90.0 93.0 136.0 91.0 91.0 90.0 91.0 87.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#6] imaging-darkroom Cycles:10 Average:98.20 Median:94.00 stddev:9.15 (9.7%) stddev-sans-first:9.58 19:01:02 INFO - PID 1648 | Values: 94.0 93.0 95.0 94.0 93.0 95.0 115.0 116.0 94.0 93.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#7] imaging-desaturate Cycles:10 Average:90.80 Median:83.50 stddev:15.05 (18.0%) stddev-sans-first:15.76 19:01:02 INFO - PID 1648 | Values: 84.0 83.0 83.0 83.0 83.0 86.0 81.0 96.0 130.0 99.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#8] json-parse-financial Cycles:10 Average:48.60 Median:49.00 stddev:1.26 (2.6%) stddev-sans-first:1.00 19:01:02 INFO - PID 1648 | Values: 51.0 49.0 47.0 49.0 48.0 49.0 48.0 47.0 50.0 48.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#9] json-stringify-tinderbox Cycles:10 Average:61.40 Median:61.50 stddev:8.14 (13.2%) stddev-sans-first:8.05 19:01:02 INFO - PID 1648 | Values: 53.0 53.0 65.0 63.0 65.0 60.0 57.0 57.0 60.0 81.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#10] stanford-crypto-aes Cycles:10 Average:91.40 Median:82.50 stddev:11.96 (14.5%) stddev-sans-first:12.07 19:01:02 INFO - PID 1648 | Values: 81.0 99.0 108.0 106.0 107.0 83.0 82.0 83.0 82.0 83.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#11] stanford-crypto-ccm Cycles:10 Average:153.00 Median:149.00 stddev:17.94 (12.0%) stddev-sans-first:19.02 19:01:02 INFO - PID 1648 | Values: 151.0 141.0 144.0 143.0 147.0 143.0 141.0 155.0 199.0 166.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:196.20 Median:188.00 stddev:20.99 (11.2%) stddev-sans-first:20.82 19:01:02 INFO - PID 1648 | Values: 175.0 185.0 187.0 189.0 202.0 236.0 232.0 186.0 185.0 185.0 19:01:02 INFO - PID 1648 | 19:01:02 INFO - PID 1648 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:68.00 stddev:1.20 (1.8%) stddev-sans-first:1.27 19:01:02 INFO - PID 1648 | Values: 68.0 69.0 67.0 67.0 68.0 68.0 68.0 71.0 67.0 68.0 19:01:02 INFO - PID 1648 | -------- Summary: end -------- 19:01:02 INFO - PID 1648 | 19:01:03 INFO - TEST-INFO | 1648: exit 0 19:01:03 INFO - TEST-OK | kraken | took 61677ms 19:01:03 INFO - SUITE-END | took 1181s 19:01:03 INFO - Completed test suite (00:19:41) 19:01:03 INFO - javascript benchmark 19:01:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7771.03959541694, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3425.5889293284777, 3427.160345715299, 3468.1156305142185, 3475.843587038583, 3492.755507114503, 4927.876803079925, 5823.67969761663, 5949.9587572174805, 6089.573729838913, 6108.328083910772, 5856.063029915214, 5926.9407305926925, 5932.466078052975, 5934.139549765281, 5934.525237980962, 5140.100482415572, 5167.224916262572, 5186.92219616706, 5224.268602395668, 5228.816991405282, 5254.369475662907, 5282.973585132069, 5284.392294886099, 5287.656302340336, 5310.4424035476295, 5272.209168624718, 5278.102722537178, 5297.44376840432, 5298.47015298469, 5304.761285742132, 5284.365876094893, 5286.15421532553, 5290.947090529084, 5299.231611416361, 5302.946970530284, 4960.156773348539, 4976.402831660224, 5216.347956505437, 5216.400113986902, 5302.628815982883, 5764.423557644244, 6126.0, 6127.234095738033, 6159.87680246397, 6186.0, 6283.088952101965, 6375.553711240215, 6380.330065343165, 6405.5516113872045, 6406.03909413587, 6320.93679063208, 6321.620702757803, 6394.328595497453, 6437.871242575169, 6461.773837915697, 6184.907226391608, 6223.626582405025, 6233.376662333731, 6237.251529816361, 6242.1261023456755, 6630.569013014138, 6632.602043877383, 6661.40047395727, 6665.333466653393, 6677.732890684415, 6242.157308763259, 6273.466755325757, 6316.905246421307, 6326.304106548276, 6344.555881088372, 6361.968190159112, 6400.199975003125, 6427.839304017343, 6440.452561532228, 6448.838779030561, 6298.338674439209, 6334.588251763621, 6352.7776527822225, 6356.841078973062, 6365.649889256136, 6240.4071613196675, 6261.5616906816085, 6362.236531616143, 6372.171617689671, 6387.808365749035, 5130.538251557377, 5162.767675454538, 5193.818216362409, 5248.160294352885, 5268.1834315681135, 4647.09381670571, 4652.302154676758, 4656.254999200111, 4681.29780532916, 4700.858974230779, 6092.45167934888, 6137.355577664371, 6140.692965351804, 6146.78486252979, 6148.907766383508, 6248.875022499525, 6320.304766475684, 6410.358964103646, 6449.097126402307, 6473.579217350857, 6091.878162436727, 6120.456967560027, 6126.151269885628, 6134.6625935573975, 6142.938570614237, 5648.63283886546, 5653.180288858092, 5654.434556544313, 5658.688772117573, 5668.093105103246, 6387.361263873576, 6396.859705348972, 6430.549861509649, 6472.126262954441, 6496.675166241764, 6329.651869147241, 6332.176817013758, 6338.429541341117, 6351.2696039956, 6461.450776684034, 6678.83302917431, 6723.630200339028, 6729.528932974645, 6729.798106056826, 6743.52795304324, 6337.429631333202, 6373.808785736435, 6377.649229292248, 6387.233531976192, 6388.009858471944, 6244.438000579969, 6320.146780184617, 6335.208098987627, 6445.484361251182, 6476.255230648536, 6186.845328866634, 6277.089821975879, 6280.152179455715, 6285.842853928689, 6294.307626161027, 4887.0, 6216.627002379872, 6228.283747369111, 6270.529710271839, 6278.717457714505, 6257.405546473078, 6271.96864015677, 6281.623102613858, 6287.905681414691, 6314.400131987454, 6256.843578910563, 6269.216347956505, 6288.5598008138995, 6289.7169627365965, 6295.905561416674, 6071.423214794588, 6127.678242056309, 6134.528431832502, 6154.907676384768, 6193.38066193377], "value": 5888.785372498954, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [799.1688643810437, 818.1981657975184, 1137.5677242647794, 1155.3010428690648, 1157.5659127827064, 36810.631893681086, 37179.62820371799, 37319.62680373195, 37361.06597335061, 37394.439083413774, 39163.0, 39357.40963885544, 39419.40870886939, 39455.0, 39549.60450395495, 6116.7553297868035, 6279.780207692709, 8573.271271941892, 8664.783380415476, 8680.86978695317, 8454.830903381928, 8636.481811091355, 8646.308295336357, 8663.740087797376, 8664.696735614254, 8683.305335573139, 8744.300455963508, 8748.431351962103, 8753.824923501526, 8760.299176065899, 8695.043545210021, 8723.738287851374, 8725.563721813884, 8736.301095912311, 8747.518886461243, 8535.274501667365, 8598.785030374229, 8602.784930376729, 8609.784755381103, 8637.568121593895, 37655.4351684725, 37697.62302376969, 37824.81087594572, 37840.81079594584, 37912.43131353032, 3616.764910280823, 3652.7808331500196, 3653.561572611277, 3659.3596120678776, 3662.066173125836, 30650.23374415635, 30674.693253067406, 30721.38557228843, 30730.23174420635, 30799.0, 3138.3837323778043, 3161.4309424303615, 3183.761217908666, 3210.0369889033195, 3214.951775723366, 264287.0, 270122.0, 270263.64868175535, 270385.0, 270981.6450917752, 39572.0, 40193.59806401899, 47263.0, 48586.75706621444, 48736.0, 49498.50501495011, 49784.50215497871, 49824.75087624539, 49852.75073624609, 50000.24999625009, 14022.719545609234, 14171.716565668836, 14191.78712319316, 14223.07550009246, 14223.359948802121, 11626.069914406828, 11629.0, 11630.88369116315, 11639.418029098511, 11666.824997625043, 12417.006639469002, 12417.379131043412, 12428.502859885684, 12437.315947622968, 12572.0, 4288.541895755458, 4339.717918335298, 4864.294677271776, 5166.509181627739, 5233.685978841206, 903.4940433357341, 1019.2050200843354, 1022.8261195596766, 1060.9946950265353, 1075.2204651627508, 24101.879490602787, 24119.397015074414, 24148.396290092536, 24258.878705606712, 24462.143824966042, 973.9366941148803, 1105.9336439813476, 1107.4296737180198, 1150.6663067710438, 1150.9079273658087, 9206.434906065984, 11076.833847492295, 11850.11124165691, 12399.566015189424, 12435.316057616918, 343537.0, 344727.0, 345483.0, 345690.0, 346428.0, 52357.0, 52487.475125249024, 52521.737391312796, 52625.42123736294, 52697.73651131796, 1466.288849907794, 1493.193675415307, 1529.694061187767, 1534.884883633732, 1548.6515534004984, 22955.081796727944, 23608.29175124749, 23612.409689757205, 23704.762952370256, 23704.881475592512, 30838.458077096504, 33007.66992330046, 38063.80968095252, 38076.23847523089, 38124.2375152501, 307030.0, 307500.0, 307894.0, 308486.0, 310073.0, 29571.704282956896, 29973.55039674363, 31885.0, 34127.31745365128, 34177.0, 31004.844975774977, 42223.0, 42716.0, 42907.35638965481, 43080.78459607682, 28256.85871570629, 28961.56557651679, 30195.0, 30204.0, 30517.23706907345, 22044.0, 27607.0, 28110.87556497717, 29690.851545742134, 29965.55396129473, 35895.10262243465, 36152.819235903655, 36244.81877590595, 36266.27467450583, 36312.45531317085, 29111.0, 29509.0, 30108.096757097323, 30478.695213047587, 30531.694683052887, 18759.437216883514, 23702.170424035077, 23742.525149497258, 23778.0, 23890.402739930945], "value": 17182.217381505707, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [372.2722078336851, 400.143692498054, 434.5784588948721, 443.28852192231466, 459.4050704337884, 15386.384544618215, 21398.786012139873, 22044.448888777788, 22963.311100666964, 23152.30543083706, 22312.0, 22780.0, 23054.423639408982, 23162.65256021161, 23546.0, 6220.875582488359, 6283.717232724538, 6319.0, 6400.9039864402075, 6439.291677915426, 3749.212665340282, 6091.177691011701, 6336.081268216106, 6366.713497892606, 6374.490040796725, 3526.453399723034, 3946.9802650986703, 3950.940735888964, 5983.700814959257, 6300.149479820235, 6061.939380606225, 6276.744651069805, 6286.497080233616, 6305.4009869062365, 6309.211348581451, 5542.0855558832745, 5566.109422492423, 5607.990561698893, 5610.69141197234, 5622.072358060915, 27801.721982780116, 27905.441891162063, 28110.859445702845, 28147.296317592223, 28163.85918070417, 2200.702905107806, 2247.8651280923195, 2270.648049552322, 2273.1930164791593, 2279.7036385269976, 17293.481195564153, 18424.907875460536, 18492.89042657445, 18520.073996300132, 18602.34891778795, 1993.063260267672, 2046.4576887124924, 2084.7081408602808, 2113.619548481273, 2180.531185795046, 143216.0, 143778.0, 144464.0, 145799.0, 167044.0, 11118.833217501744, 11203.775924481628, 11642.94178529102, 12704.237745735285, 12713.364331783374, 15632.2965466552, 15692.607684807743, 16352.836471635132, 16816.915915420588, 16866.987980721195, 8960.955195223982, 9049.81900361989, 9125.0, 9270.629174833066, 9282.675106371245, 8669.349798765117, 8698.21716045559, 8709.390342675952, 8842.0, 8888.866667, 6630.403263706289, 7804.648790804427, 7852.921470785333, 7909.327707144842, 7965.522068675898, 2187.846850720466, 2208.1608988584235, 2210.071769856664, 2212.816143363293, 2363.0, 814.3770015937812, 916.4776077635684, 963.8409662405764, 964.252704154272, 989.3223142147652, 20505.0, 20662.690059649118, 21465.316835395577, 21573.892130539247, 21610.567788644454, 977.5063592885547, 984.4831463481586, 1001.8447140693204, 1013.2350075692952, 1014.320405328417, 10596.735081622866, 11133.498992545356, 13202.867971320164, 14045.08706934046, 14362.281885905872, 685189.0, 686291.0, 694123.0, 720990.0, 725818.3709081527, 150854.0, 156516.0, 158002.41997580108, 160448.0, 162311.0, 1169.502961241469, 1173.318596951734, 1227.0122551346074, 1227.8894899459092, 1234.7592219517107, 21526.138954441645, 22175.44561385978, 22726.545469090855, 22749.88625056864, 22844.086236550353, 57680.711596441746, 62773.68613156905, 63741.36258637354, 64374.24155012257, 64753.35246647473, 73941.44256101761, 79723.20276797158, 88154.11845881458, 91372.08627913636, 92652.0, 8746.256568191775, 8834.249088827522, 8842.602082906413, 9092.681756138769, 9229.769255768659, 14596.708065838837, 14649.633759156106, 14816.777748333569, 15111.0, 15217.239138043275, 11941.940290298493, 12106.75786484283, 12230.755384892074, 12329.568465103679, 12416.006719462603, 11355.772884542428, 11572.59495917639, 11580.536778529102, 11950.760984780081, 11985.760284794082, 12153.392330383622, 12174.14780965324, 12412.56556020535, 12491.625251242478, 12496.562620308245, 8891.688790892287, 9188.678396256098, 9223.538823058954, 9291.535423228675, 9369.578368973276, 11715.414229288672, 11834.76330473403, 12126.333051682243, 12175.086868485076, 12229.633111006684], "value": 10716.612884327933, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1377.7588921938661, 1442.4374493947362, 1467.794508768772, 1473.1234915225436, 1476.9040012399196, 11130.49912753926, 11645.592404265832, 11655.41722913854, 11656.0, 11675.0, 11275.154363422735, 11438.828417573743, 11862.347570883598, 11895.345755983417, 11899.52401903925, 2486.9626955595636, 2539.3651587103222, 2539.7079335876424, 2540.314115188898, 2552.119518766023, 2435.5981263091658, 2473.8515689058713, 2507.2352932355625, 2510.083819405913, 2510.57320255557, 2403.471236328005, 2494.3390001649577, 2495.226479791261, 2495.9376015599573, 2500.6749122614033, 2476.2571228631336, 2494.825362224627, 2498.0757119865675, 2524.6591710119083, 2530.114459939016, 2469.333280014404, 2470.3824043989002, 2496.488219914931, 2500.2124330835727, 2504.3113143885394, 11707.824382634268, 11828.704282392922, 11837.053035757119, 11838.0, 11845.0, 6456.644884531396, 6702.061711360413, 6712.160979877515, 6712.362325579063, 6730.899036514456, 4009.498812648419, 4229.6827737919475, 4288.978555107205, 4294.978525107385, 4310.181065597527, 1220.4020030185234, 1244.3342811595753, 1298.4416700818645, 1300.7658621448136, 1301.0632344711792, 13085.88769954555, 14526.418943242257, 15005.399784008627, 16340.83659163405, 16471.588210294718, 5221.086309895753, 5361.115415956324, 5362.91955620666, 5471.8084867029465, 5764.596478246566, 12283.263004219596, 12287.631371058882, 12292.262464252173, 12304.938475307566, 12313.753724925451, 6548.70530826114, 6550.672466376662, 6552.0, 6552.868942621121, 6570.047343135218, 5457.7271136443815, 6063.333033366326, 6079.787207447718, 6129.478994285536, 6132.110843927606, 4286.978565107154, 4399.0, 4422.137683151755, 4447.488538818013, 4449.377087207794, 1268.4545645372577, 1269.7079671675567, 1270.9428075736614, 1288.3365066990507, 1288.6842723532607, 761.2615762710162, 784.7018133109382, 785.921407859221, 788.7594283743399, 802.2378740196837, 5389.784408623611, 5676.517496012802, 5906.877693238357, 6495.577787443801, 6813.931860681429, 818.2472125644422, 829.2081062585216, 875.1205038935828, 946.7103304554954, 956.9808603827885, 3926.6858651307825, 4099.528554216244, 4258.276093064186, 4272.764997925082, 4330.653547716175, 12551.560695375618, 14680.55958321252, 15242.314095865713, 15246.542603721906, 15326.463573774865, 14575.489857854924, 14883.851161488463, 15199.62000949963, 15219.61950951213, 15230.619234519225, 10828.891711082788, 10931.234813563133, 10931.890681093088, 11063.72340691473, 11065.557377704963, 5556.666600004012, 6697.966510167418, 6956.79129626112, 7033.980072889504, 7055.506114571931, 13004.414801333773, 13341.19952802835, 13424.39590218423, 13610.659733506742, 13631.522896698569, 12479.564850041997, 13839.792403114163, 13993.370298336802, 14067.366968486238, 14143.64640883945, 9483.715488535356, 9653.082957119062, 9659.130678238993, 9718.562664680248, 9729.221662267144, 10336.328138670962, 10366.585336586451, 10583.100436462986, 10692.059683587077, 10711.732206694895, 9016.368854180144, 9111.498867562348, 9130.360874738704, 9134.132257435533, 9347.345685801929, 8857.911420885708, 8890.33322500828, 9030.503322317336, 9036.960749513628, 9181.449113053239, 8060.193980601893, 8103.878441823495, 8122.83754324922, 8168.754937351889, 8205.71280005197, 5874.882502350015, 6010.248718910137, 6043.09353596964, 6076.331603523698, 6099.024156134997, 4825.6139508839915, 6184.288806787277, 6229.359127450079, 6230.626162430269, 6249.968755155349], "value": 5395.557914675809, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [395.1543696489513, 401.955784863665, 403.0873505217741, 409.6333781265767, 409.7357204603029, 5835.562332825039, 5858.297004359483, 5893.38119497453, 5895.4988825949795, 5933.673647949361, 5612.102063669814, 5682.403347648499, 5729.942700572993, 5758.3665796762425, 5791.565632577563, 3920.627540383659, 4378.686394081778, 4433.623142032931, 4445.933311000337, 4450.821967121311, 3637.981810090946, 4444.488883778358, 4464.196444639963, 4473.957708758043, 4483.730976141425, 4225.936610950838, 4348.152110338469, 4408.319053590256, 4412.205802955466, 4426.159029784347, 4216.64158546524, 4403.317485789692, 4403.581659742319, 4416.227160246976, 4418.779061046935, 3893.552241492236, 3939.7439166454087, 3955.4462375267485, 3956.287868183726, 4013.578574249691, 5442.809501667462, 5644.0, 5695.14572814077, 5705.2012718219485, 5719.6854173020465, 4348.239058164809, 4945.837728133922, 5304.787808487695, 5333.919991200135, 5360.865978350534, 6132.386761323876, 6174.691265436711, 6180.2892667343385, 6187.0, 6204.81385558434, 4367.388565600819, 4442.600165985077, 4461.687681862271, 4507.2788353863225, 4536.954630453686, 6301.338359472235, 6431.517636177212, 6452.6451045192025, 6511.153550038512, 6531.779195073697, 3544.7164226861787, 3706.629337066272, 3904.4338570907057, 4266.573342665709, 4492.573205545468, 4167.124903770182, 4212.7472351659, 4288.378185163134, 4481.5070342262325, 4528.43394575678, 4326.891827704332, 5334.3865455473115, 5361.490658387447, 5362.195670649434, 5363.839084827461, 5127.897442051213, 5152.6650767699975, 6247.312795592481, 6264.310925798159, 6264.68676566179, 1414.971700565983, 1448.044290768088, 1473.5726639274706, 1482.5174825174824, 1492.6865358274883, 921.0605182713595, 959.2278216036158, 994.9104580587637, 995.4425521707867, 1001.7628229137156, 5818.650880947157, 5825.883482330414, 5828.5337173026055, 5835.883282334329, 5870.178175055496, 6245.221640907276, 6307.873842523124, 6318.273398559132, 6339.841503962346, 6359.141515895295, 5001.8499445016705, 5554.666719996813, 5786.768529258782, 5805.912911306334, 5879.52963762898, 2740.3560163361594, 2848.6011958325853, 3313.5692359993045, 3325.9667403325657, 3328.3509715605223, 5760.8271751847515, 6191.9690401547705, 6509.218893732687, 6545.378189072126, 6560.507961902877, 6085.883686894643, 6439.238799260757, 6454.580452270588, 6495.902561461582, 6578.947368421125, 5207.5313221809465, 6528.21661400635, 6550.312217217123, 6580.374864387875, 6596.4393026592315, 5159.974200128975, 5933.614315069507, 5935.40645935403, 6345.302016778059, 6358.268799088164, 5472.753726082416, 6244.189185136442, 6286.748530058747, 6293.37066293367, 6326.810195694136, 5737.655740655574, 6370.90443643355, 6404.935950640434, 6433.204409398478, 6500.414962653384, 3358.7145092667397, 3550.3076900005285, 3760.680342170946, 4058.8635182149046, 4235.216484950294, 5509.944900550943, 6302.527310451643, 6307.653079080695, 6338.954072577973, 6346.650934198663, 5532.30846144232, 6083.178770866053, 6114.3796893824065, 6163.84590385244, 6182.567220294716, 5293.0, 5604.579656525859, 6013.428724271187, 6076.964216987359, 6081.452669259788, 4784.378030855966, 5098.668586541863, 5200.453952335101, 6030.5778595497895, 6033.245844269466, 5113.74431278442, 5919.733611987384, 6127.295361033538, 6131.325554189125, 6153.753849845956, 5742.28221472316, 6018.699065046818, 6032.215811944595, 6035.275766907999, 6036.551227705231], "value": 4530.28901928015, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.3995783499689, 863.7374556563365, 875.5359659380529, 887.0375642427965, 892.7634176943109, 13497.0, 13883.09759865608, 17767.111644417775, 18041.639167216646, 18518.537036574093, 17566.385176518823, 18067.27730890763, 18161.63676726468, 18312.816871831277, 18408.907955460203, 985.8373368394207, 1009.530568285747, 1011.2314640872953, 1013.9847902281472, 1016.0804471952895, 695.9269276725947, 716.7992961970657, 1028.1260928211036, 1032.8399098139762, 1034.5965073621294, 892.901780804111, 1016.2784423059636, 1064.2816099133092, 1070.3524367757473, 1078.002847366187, 1058.7141471802609, 1060.363781730963, 1061.6284300494842, 1062.3041907550567, 1064.1220992680992, 781.7303030454484, 783.6199443270009, 784.7959530522048, 787.7046107709609, 788.0543347982444, 16423.507294781055, 16699.91650041754, 16908.154592270337, 17187.828121718874, 17499.95000299986, 13659.86340136596, 16725.498235052968, 16843.663126737396, 17085.316587336492, 17248.0, 13492.190468571818, 13706.45174193031, 13753.38109785062, 13841.584752457342, 13979.021468497209, 3370.3259348130473, 3398.235397035647, 3398.49022646605, 3398.5242066110764, 3400.846961886721, 88365.0, 111927.0, 112688.0, 112782.0, 113233.0, 12132.393380330948, 12191.756164876653, 12196.451159697657, 12231.143819932697, 12271.263724176399, 19834.90082549578, 20669.483262918548, 20669.689954650694, 25146.622800658006, 25291.0, 7522.473426860177, 8632.050474447931, 8861.291096712248, 8866.157715017063, 8893.688720894866, 4706.10583989041, 4819.783109760069, 4821.180399332122, 4826.541478559531, 4827.42070951488, 4281.743095414286, 4310.7929779661745, 4386.254336762758, 4417.3815665806815, 4448.199324121689, 947.5309721687671, 971.795922856194, 975.0834215837091, 976.5507866381544, 978.2027647467291, 659.2385794407467, 677.4309579952765, 679.1917618034622, 691.1774987764544, 698.7728988078793, 9998.950005250073, 10904.781904362027, 11774.0, 11807.23252988553, 11824.11319151067, 662.4203821656051, 663.8639078988796, 664.7174950645956, 670.8255853478131, 689.1385767790263, 5343.118385466432, 5468.207109969032, 5472.863178420572, 5493.121100623961, 5608.971955140199, 75269.0, 96032.0, 102485.0, 102843.45734813984, 103284.0, 67265.0, 90227.0, 90650.0, 92349.76775812815, 92554.87260781309, 73850.0, 75723.62138189384, 76078.61960690272, 76590.0, 77401.45197096026, 10352.637657681946, 11178.776424471627, 11198.0, 11688.766224675288, 12142.878571214174, 39233.41149882697, 39289.21421571496, 39707.0, 39750.60249397585, 41862.79068604638, 66716.0, 67828.0, 69884.10811243164, 70292.59414811777, 71010.64494677493, 13149.408276627766, 15858.92070539686, 16069.0, 16224.432144874872, 16306.836931631007, 17794.288228470716, 18058.458246252634, 18072.548186295448, 18326.99201543928, 18385.356512521998, 16090.195490225677, 16302.59243518874, 16384.75422868682, 16513.91743041277, 16563.006219626863, 16082.67834643324, 16226.188690565188, 16423.507294781175, 16519.174041297647, 16627.3349066036, 12900.806487902877, 15875.444359447363, 15896.284667189773, 16017.599560011093, 16192.271347789614, 11825.0, 11894.107941904218, 12077.818832717341, 12090.335031573348, 12133.272003679807, 11043.779124417628, 12923.15999460032, 13018.674533136747, 13064.54274100402, 13213.339333033502], "value": 8308.394940544758, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1487.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 114.0, 113.0, 114.0, 114.0, 112.0, 115.0, 112.0, 113.0, 114.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 108.0, 105.0, 149.0, 123.0, 113.0, 108.0, 105.0, 131.0, 107.0], "value": 119.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 182.0, 186.0, 186.0, 195.0, 208.0, 183.0, 178.0, 180.0, 205.0], "value": 188.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 57.0, 72.0, 80.0, 74.0, 78.0, 76.0, 84.0, 84.0, 56.0], "value": 74.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 68.0, 113.0, 106.0, 102.0, 102.0, 87.0, 69.0, 70.0, 85.0], "value": 89.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 90.0, 90.0, 93.0, 136.0, 91.0, 91.0, 90.0, 91.0, 87.0], "value": 94.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 95.0, 94.0, 93.0, 95.0, 115.0, 116.0, 94.0, 93.0], "value": 98.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 83.0, 83.0, 86.0, 81.0, 96.0, 130.0, 99.0], "value": 90.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [51.0, 49.0, 47.0, 49.0, 48.0, 49.0, 48.0, 47.0, 50.0, 48.0], "value": 48.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 65.0, 63.0, 65.0, 60.0, 57.0, 57.0, 60.0, 81.0], "value": 61.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 99.0, 108.0, 106.0, 107.0, 83.0, 82.0, 83.0, 82.0, 83.0], "value": 91.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 141.0, 144.0, 143.0, 147.0, 143.0, 141.0, 155.0, 199.0, 166.0], "value": 153.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [175.0, 185.0, 187.0, 189.0, 202.0, 236.0, 232.0, 186.0, 185.0, 185.0], "value": 196.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 67.0, 67.0, 68.0, 68.0, 68.0, 71.0, 67.0, 68.0], "value": 68.1, "unit": "score"}]}]} 19:01:03 INFO - Return code: 0 19:01:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:01:03 ERROR - # TBPL SUCCESS # 19:01:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:01:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:01:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:01:03 INFO - 2017-04-13 19:01:03.709 screenresolution[1652:10228] starting screenresolution argv=screenresolution get 19:01:03 INFO - 2017-04-13 19:01:03.711 screenresolution[1652:10228] Display 0: 1600x1200x32@60 19:01:03 INFO - 2017-04-13 19:01:03.718 screenresolution[1653:10231] starting screenresolution argv=screenresolution list 19:01:03 INFO - Available Modes on Display 0 19:01:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:01:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:01:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:01:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:01:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:01:03 INFO - Intel Iris: 19:01:03 INFO - Chipset Model: Intel Iris 19:01:03 INFO - Type: GPU 19:01:03 INFO - Bus: Built-In 19:01:03 INFO - VRAM (Dynamic, Max): 1536 MB 19:01:03 INFO - Vendor: Intel (0x8086) 19:01:03 INFO - Device ID: 0x0a2e 19:01:03 INFO - Revision ID: 0x0009 19:01:03 INFO - Displays: 19:01:03 INFO - Display: 19:01:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:01:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:01:03 INFO - Display Serial Number: 1600x1200 60 19:01:03 INFO - Main Display: Yes 19:01:03 INFO - Mirror: Off 19:01:03 INFO - Online: Yes 19:01:03 INFO - Rotation: Supported 19:01:03 INFO - Return code: 0 19:01:03 INFO - Running post-action listener: _resource_record_post_action 19:01:03 INFO - [mozharness: 2017-04-14 02:01:03.968595Z] Finished run-tests step (success) 19:01:03 INFO - Running post-run listener: _resource_record_post_run 19:01:04 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 38712320; Write bytes: 513513472; Read time: 252; Write time: 1643 19:01:04 INFO - TinderboxPrint: CPU usage
53.5% 19:01:04 INFO - TinderboxPrint: I/O read bytes / time
38,712,320 / 252 19:01:04 INFO - TinderboxPrint: I/O write bytes / time
513,513,472 / 1,643 19:01:04 INFO - TinderboxPrint: CPU idle
2,235.6 (46.5%) 19:01:04 INFO - TinderboxPrint: CPU system
955.7 (19.9%) 19:01:04 INFO - TinderboxPrint: CPU user
1,614.4 (33.6%) 19:01:04 INFO - TinderboxPrint: Swap in / out
246,042,624 / 0 19:01:04 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 196760064; Write bytes: 195756032; Read time: 14948; Write time: 457 19:01:04 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 37721088; Write bytes: 300918784; Read time: 238; Write time: 1135 19:01:04 INFO - Running post-run listener: _upload_blobber_files 19:01:04 INFO - Blob upload gear active. 19:01:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:01:04 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 19:01:04 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:01:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:01:04 INFO - (blobuploader) - INFO - Open directory for files ... 19:01:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:01:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:01:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:06 INFO - (blobuploader) - INFO - Done attempting. 19:01:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:01:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:07 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:01:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:07 INFO - (blobuploader) - INFO - Done attempting. 19:01:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:01:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:01:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:08 INFO - (blobuploader) - INFO - Done attempting. 19:01:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:01:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:01:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:01:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:01:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:01:10 INFO - (blobuploader) - INFO - Done attempting. 19:01:10 INFO - (blobuploader) - INFO - Iteration through files over. 19:01:10 INFO - Return code: 0 19:01:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:01:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:01:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c4f0e9225efc107916ebe0179f0c8eed51e008bdf85d2ccf251a909e7b84bef8b2976f3b8a232f1a3a7898596583053c9bd2dbdd18f00477e9ce00ec2a70709a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559445e92bc19cdd05a5af1ec994736d3c13e7406230185e15636b0e6ab6bfc9254b082729cdd414c31fac8d477250121d29df73131ce5a0edd2df7049607429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a5137d11fa4d08ebf68df876f73c145458c74c63c329ed886a8d2c90ef18aa312a4cb5bdf5389c47e7b328f3add480b21e06a507b5a1690674797a0340b282f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cdd26f851e8987d59604c7a49cd9aee828716cfefa541307f95654bc7aedce28617cfc4130078e558dc0be8f10141ea9d884d79da9721d3bbe712ea24d5b0c6f"} 19:01:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:01:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:01:10 INFO - Contents: 19:01:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c4f0e9225efc107916ebe0179f0c8eed51e008bdf85d2ccf251a909e7b84bef8b2976f3b8a232f1a3a7898596583053c9bd2dbdd18f00477e9ce00ec2a70709a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559445e92bc19cdd05a5af1ec994736d3c13e7406230185e15636b0e6ab6bfc9254b082729cdd414c31fac8d477250121d29df73131ce5a0edd2df7049607429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a5137d11fa4d08ebf68df876f73c145458c74c63c329ed886a8d2c90ef18aa312a4cb5bdf5389c47e7b328f3add480b21e06a507b5a1690674797a0340b282f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cdd26f851e8987d59604c7a49cd9aee828716cfefa541307f95654bc7aedce28617cfc4130078e558dc0be8f10141ea9d884d79da9721d3bbe712ea24d5b0c6f"} 19:01:10 INFO - Running post-run listener: copy_logs_to_upload_dir 19:01:10 INFO - Copying logs to upload dir... 19:01:10 INFO - mkdir: /builds/slave/test/build/upload/logs 19:01:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1284.337794 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 24 secs) (at 2017-04-13 19:01:10.142793) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 19:01:10.146745) ========= 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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c4f0e9225efc107916ebe0179f0c8eed51e008bdf85d2ccf251a909e7b84bef8b2976f3b8a232f1a3a7898596583053c9bd2dbdd18f00477e9ce00ec2a70709a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559445e92bc19cdd05a5af1ec994736d3c13e7406230185e15636b0e6ab6bfc9254b082729cdd414c31fac8d477250121d29df73131ce5a0edd2df7049607429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a5137d11fa4d08ebf68df876f73c145458c74c63c329ed886a8d2c90ef18aa312a4cb5bdf5389c47e7b328f3add480b21e06a507b5a1690674797a0340b282f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cdd26f851e8987d59604c7a49cd9aee828716cfefa541307f95654bc7aedce28617cfc4130078e558dc0be8f10141ea9d884d79da9721d3bbe712ea24d5b0c6f"} build_url:https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010983 build_url: 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/c4f0e9225efc107916ebe0179f0c8eed51e008bdf85d2ccf251a909e7b84bef8b2976f3b8a232f1a3a7898596583053c9bd2dbdd18f00477e9ce00ec2a70709a", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/559445e92bc19cdd05a5af1ec994736d3c13e7406230185e15636b0e6ab6bfc9254b082729cdd414c31fac8d477250121d29df73131ce5a0edd2df7049607429", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/a5137d11fa4d08ebf68df876f73c145458c74c63c329ed886a8d2c90ef18aa312a4cb5bdf5389c47e7b328f3add480b21e06a507b5a1690674797a0340b282f7", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/cdd26f851e8987d59604c7a49cd9aee828716cfefa541307f95654bc7aedce28617cfc4130078e558dc0be8f10141ea9d884d79da9721d3bbe712ea24d5b0c6f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 19:01:10.179673) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 19:01:10.179974) ========= 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.LPuxt7z2jS/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.JVy9EeAwKs/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.005157 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 19:01:10.227913) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 19:01:10.228199) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 19:01:10.228509) ========= ========= Total master_lag: 0.17 =========