builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0094 starttime: 1475176553.34 results: success (0) buildid: 20160929100439 builduid: c742fcd76585495ba700dfddc77615dd revision: 1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.340001) ========= master: http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.340991) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.341664) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.364047) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.364538) ========= 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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-29 12:15:53-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 150M=0s 2016-09-29 12:15:53 (150 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.099877 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.490695) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.491384) ========= 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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.021229 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:53.541342) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 12:15:53.541658) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9BXDKLd5A0/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.WQZfJDFQPf/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-29 12:15:53,617 truncating revision to first 12 chars 2016-09-29 12:15:53,617 Setting DEBUG logging. 2016-09-29 12:15:53,617 attempt 1/10 2016-09-29 12:15:53,617 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/1723fbc9d9d8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-29 12:15:54,641 unpacking tar archive at: fx-team-1723fbc9d9d8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.286909 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-29 12:15:54.850427) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:54.850767) ========= script_repo_revision: 1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:54.851251) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:54.851520) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-29 12:15:54.869732) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-29 12:15:54.870112) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.9BXDKLd5A0/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.WQZfJDFQPf/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:15:55 INFO - MultiFileLogger online at 20160929 12:15:55 in /builds/slave/test 12:15:55 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 12:15:55 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:15:55 INFO - {'append_to_log': False, 12:15:55 INFO - 'base_work_dir': '/builds/slave/test', 12:15:55 INFO - 'blob_upload_branch': 'Fx-Team', 12:15:55 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:15:55 INFO - 'branch': 'Fx-Team', 12:15:55 INFO - 'buildbot_json_path': 'buildprops.json', 12:15:55 INFO - 'config_files': ('talos/mac_config.py',), 12:15:55 INFO - 'default_actions': ('clobber', 12:15:55 INFO - 'read-buildbot-config', 12:15:55 INFO - 'download-and-extract', 12:15:55 INFO - 'populate-webroot', 12:15:55 INFO - 'create-virtualenv', 12:15:55 INFO - 'install', 12:15:55 INFO - 'run-tests'), 12:15:55 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:15:55 INFO - 'download_minidump_stackwalk': True, 12:15:55 INFO - 'download_symbols': 'ondemand', 12:15:55 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:15:55 INFO - 'tooltool.py': '/tools/tooltool.py', 12:15:55 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:15:55 INFO - '/tools/misc-python/virtualenv.py')}, 12:15:55 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:15:55 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:15:55 INFO - 'installer_path': 'installer.exe', 12:15:55 INFO - 'log_level': 'info', 12:15:55 INFO - 'log_name': 'talos', 12:15:55 INFO - 'log_to_console': True, 12:15:55 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:15:55 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:15:55 INFO - 'opt_config_files': (), 12:15:55 INFO - 'pip_index': False, 12:15:55 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:15:55 INFO - 'cmd': ('bash', 12:15:55 INFO - '-c', 12:15:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:15:55 INFO - 'enabled': True, 12:15:55 INFO - 'halt_on_failure': False, 12:15:55 INFO - 'name': 'check_screen_resolution'},), 12:15:55 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:15:55 INFO - 'cmd': ('bash', 12:15:55 INFO - '-c', 12:15:55 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:15:55 INFO - 'enabled': True, 12:15:55 INFO - 'halt_on_failure': False, 12:15:55 INFO - 'name': 'check_screen_resolution'},), 12:15:55 INFO - 'run_cmd_checks_enabled': True, 12:15:55 INFO - 'sps_profile': False, 12:15:55 INFO - 'sps_profile_interval': 0, 12:15:55 INFO - 'suite': 'dromaeojs', 12:15:55 INFO - 'system_bits': '32', 12:15:55 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:15:55 INFO - 'title': 't-yosemite-r7-0094', 12:15:55 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:15:55 INFO - 'use_talos_json': True, 12:15:55 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:15:55 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:15:55 INFO - 'work_dir': 'build'} 12:15:55 INFO - [mozharness: 2016-09-29 19:15:55.077377Z] Running clobber step. 12:15:55 INFO - Running pre-action listener: _resource_record_pre_action 12:15:55 INFO - Running main action method: clobber 12:15:55 INFO - rmtree: /builds/slave/test/build 12:15:55 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:15:56 INFO - Running post-action listener: _resource_record_post_action 12:15:56 INFO - [mozharness: 2016-09-29 19:15:56.578983Z] Finished clobber step (success) 12:15:56 INFO - [mozharness: 2016-09-29 19:15:56.579144Z] Running read-buildbot-config step. 12:15:56 INFO - Running pre-action listener: _resource_record_pre_action 12:15:56 INFO - Running main action method: read_buildbot_config 12:15:56 INFO - Using buildbot properties: 12:15:56 INFO - { 12:15:56 INFO - "project": "", 12:15:56 INFO - "product": "firefox", 12:15:56 INFO - "script_repo_revision": "production", 12:15:56 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 12:15:56 INFO - "repository": "", 12:15:56 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 12:15:56 INFO - "buildid": "20160929100439", 12:15:56 INFO - "pgo_build": "False", 12:15:56 INFO - "basedir": "/builds/slave/test", 12:15:56 INFO - "buildnumber": 161, 12:15:56 INFO - "slavename": "t-yosemite-r7-0094", 12:15:56 INFO - "master": "http://buildbot-master134.bb.releng.scl3.mozilla.com:8201/", 12:15:56 INFO - "platform": "yosemite_r7", 12:15:56 INFO - "scheduler": "tests-fx-team-macosx64-talos", 12:15:56 INFO - "branch": "fx-team", 12:15:56 INFO - "repo_path": "integration/fx-team", 12:15:56 INFO - "revision": "1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522", 12:15:56 INFO - "stage_platform": "macosx64", 12:15:56 INFO - "builduid": "c742fcd76585495ba700dfddc77615dd", 12:15:56 INFO - "slavebuilddir": "test" 12:15:56 INFO - } 12:15:56 INFO - Found installer url https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 12:15:56 INFO - Running post-action listener: _resource_record_post_action 12:15:56 INFO - [mozharness: 2016-09-29 19:15:56.582441Z] Finished read-buildbot-config step (success) 12:15:56 INFO - [mozharness: 2016-09-29 19:15:56.582567Z] Running download-and-extract step. 12:15:56 INFO - Running pre-action listener: _resource_record_pre_action 12:15:56 INFO - Running main action method: download_and_extract 12:15:56 INFO - mkdir: /builds/slave/test/build/tests 12:15:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:15:56 INFO - trying https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 12:15:56 INFO - Downloading https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:15:56 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 12:15:58 INFO - Downloaded 1199 bytes. 12:15:58 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 12:15:58 INFO - Using the following test package requirements: 12:15:58 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:15:58 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 12:15:58 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 12:15:58 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'jsshell-mac.zip'], 12:15:58 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 12:15:58 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 12:15:58 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 12:15:58 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 12:15:58 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 12:15:58 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 12:15:58 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 12:15:58 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:15:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:15:58 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:15:58 INFO - Fetch https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:15:58 INFO - Http code: 200 12:15:58 INFO - Content-Encoding: None 12:15:58 INFO - Content-Type: application/zip 12:15:58 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 12:15:58 INFO - x-amz-cf-id: 39DAetp8BwQ4F-whFyr_6VtuCCibv28JRccixkG0QWSaGXqOxcnHdQ== 12:15:58 INFO - x-amz-version-id: hqKsJsdQHsX0tTNhugUv8WasTvwW3Cff 12:15:58 INFO - x-cache: Hit from cloudfront 12:15:59 INFO - Expected file size: 16473020 12:15:59 INFO - Obtained file size: 16473020 12:16:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 12:16:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 12:16:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:16:05 INFO - Fetch https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 12:16:06 INFO - Http code: 200 12:16:06 INFO - Content-Encoding: None 12:16:06 INFO - Content-Type: application/zip 12:16:06 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 12:16:06 INFO - x-amz-cf-id: LF9ZyFpGpUB_QswjoS-vAbSuiZ43WUpXmV0Z_1_rORXQtAKns4_z6A== 12:16:06 INFO - x-amz-version-id: hqKsJsdQHsX0tTNhugUv8WasTvwW3Cff 12:16:06 INFO - x-cache: Hit from cloudfront 12:16:06 INFO - Expected file size: 16473020 12:16:06 INFO - Obtained file size: 16473020 12:16:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 12:16:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:16:12 INFO - Fetch https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 12:16:14 INFO - Http code: 200 12:16:14 INFO - Content-Encoding: None 12:16:14 INFO - Content-Type: application/zip 12:16:14 INFO - via: 1.1 73fa5a1ee49cf827e596b502b5927eab.cloudfront.net (CloudFront) 12:16:14 INFO - x-amz-cf-id: 8CEUw2jjBMnGtAQmGNM_SvoP3bxd9mU86SaeGXiN6HJoG7MLXjog-g== 12:16:14 INFO - x-amz-version-id: j4FgNw1e4gZEJk2iKVU4QNBNRErKgvIb 12:16:14 INFO - x-cache: Hit from cloudfront 12:16:14 INFO - Expected file size: 14414067 12:16:14 INFO - Obtained file size: 14414067 12:16:14 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')]} 12:16:14 INFO - trying https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:16:14 INFO - Downloading https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 12:16:14 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 12:16:20 INFO - Downloaded 111789544 bytes. 12:16:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:16:20 INFO - mkdir: /builds/slave/test/properties 12:16:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:16:20 INFO - Writing to file /builds/slave/test/properties/build_url 12:16:20 INFO - Contents: 12:16:20 INFO - build_url:https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 12:16:21 INFO - Running post-action listener: _resource_record_post_action 12:16:21 INFO - Running post-action listener: set_extra_try_arguments 12:16:21 INFO - [mozharness: 2016-09-29 19:16:21.988239Z] Finished download-and-extract step (success) 12:16:21 INFO - [mozharness: 2016-09-29 19:16:21.988396Z] Running populate-webroot step. 12:16:21 INFO - Running pre-action listener: _resource_record_pre_action 12:16:21 INFO - Running main action method: populate_webroot 12:16:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:16:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:16:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:16:21 INFO - '--tppagecycles', 12:16:21 INFO - '7'], 12:16:21 INFO - 'tests': ['tsvgm']}}, 12:16:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:16:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:16:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:16:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:16:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:16:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:16:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:21 INFO - 'talos_options': ['--disable-e10s'], 12:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:16:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:21 INFO - 'talos_options': ['--disable-e10s'], 12:16:21 INFO - 'tests': ['damp', 'tps']}, 12:16:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:21 INFO - 'tests': ['damp', 'tps']}, 12:16:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:16:21 INFO - 'tests': ['dromaeo_dom']}, 12:16:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:16:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:16:21 INFO - 'tests': ['basic_compositor_video']}, 12:16:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:16:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:16:22 INFO - 'tests': ['a11yr', 12:16:22 INFO - 'ts_paint', 12:16:22 INFO - 'tpaint', 12:16:22 INFO - 'sessionrestore', 12:16:22 INFO - 'sessionrestore_no_auto_restore', 12:16:22 INFO - 'tabpaint']}, 12:16:22 INFO - 'other-e10s': {'tests': ['a11yr', 12:16:22 INFO - 'ts_paint', 12:16:22 INFO - 'tpaint', 12:16:22 INFO - 'sessionrestore', 12:16:22 INFO - 'sessionrestore_no_auto_restore', 12:16:22 INFO - 'tabpaint']}, 12:16:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:16:22 INFO - 'ts_paint', 12:16:22 INFO - 'tpaint', 12:16:22 INFO - 'sessionrestore', 12:16:22 INFO - 'sessionrestore_no_auto_restore', 12:16:22 INFO - 'tabpaint']}, 12:16:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:16:22 INFO - 'ts_paint', 12:16:22 INFO - 'tpaint', 12:16:22 INFO - 'sessionrestore', 12:16:22 INFO - 'sessionrestore_no_auto_restore', 12:16:22 INFO - 'tabpaint']}, 12:16:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:16:22 INFO - 'tests': ['a11yr', 12:16:22 INFO - 'ts_paint', 12:16:22 INFO - 'tpaint', 12:16:22 INFO - 'sessionrestore', 12:16:22 INFO - 'sessionrestore_no_auto_restore', 12:16:22 INFO - 'tabpaint']}, 12:16:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:16:22 INFO - 'tests': ['a11yr', 12:16:22 INFO - 'ts_paint', 12:16:22 INFO - 'tpaint', 12:16:22 INFO - 'sessionrestore', 12:16:22 INFO - 'sessionrestore_no_auto_restore', 12:16:22 INFO - 'tabpaint']}, 12:16:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:16:22 INFO - 'tests': ['tsvgx', 12:16:22 INFO - 'tsvgr_opacity', 12:16:22 INFO - 'tart', 12:16:22 INFO - 'tscrollx', 12:16:22 INFO - 'cart']}, 12:16:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:16:22 INFO - 'tsvgr_opacity', 12:16:22 INFO - 'tart', 12:16:22 INFO - 'tscrollx', 12:16:22 INFO - 'cart']}, 12:16:22 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:22 INFO - 'talos_options': ['--disable-e10s'], 12:16:22 INFO - 'tests': ['tp5o']}, 12:16:22 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:22 INFO - 'tests': ['tp5o']}, 12:16:22 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:22 INFO - 'talos_options': ['--disable-e10s', 12:16:22 INFO - '--xperf_path', 12:16:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:22 INFO - 'tests': ['tp5n']}, 12:16:22 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:16:22 INFO - 'talos_options': ['--xperf_path', 12:16:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:16:22 INFO - 'tests': ['tp5n']}}, 12:16:22 INFO - 'talos.zip': {'path': '', 12:16:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:16:22 INFO - Running post-action listener: _resource_record_post_action 12:16:22 INFO - [mozharness: 2016-09-29 19:16:22.007213Z] Finished populate-webroot step (success) 12:16:22 INFO - [mozharness: 2016-09-29 19:16:22.007324Z] Running create-virtualenv step. 12:16:22 INFO - Running pre-action listener: _resource_record_pre_action 12:16:22 INFO - Running main action method: create_virtualenv 12:16:22 INFO - Creating virtualenv /builds/slave/test/build/venv 12:16:22 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 12:16:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:16:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:16:22 INFO - Using real prefix '/tools/python27' 12:16:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:16:22 INFO - Installing distribute.............................................................................................................................................................................................done. 12:16:25 INFO - Installing pip.................done. 12:16:25 INFO - Return code: 0 12:16:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:16:26 INFO - Reading from file tmpfile_stdout 12:16:26 INFO - Output received: 12:16:26 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) 12:16:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:16: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')]} 12:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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')]} 12:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:26 INFO - 'HOME': '/Users/cltbld', 12:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:26 INFO - 'LOGNAME': 'cltbld', 12:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:26 INFO - 'MOZ_NO_REMOTE': '1', 12:16:26 INFO - 'NO_EM_RESTART': '1', 12:16:26 INFO - 'PAGER': '/bin/cat', 12:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:26 INFO - 'PWD': '/builds/slave/test', 12:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:26 INFO - 'SHELL': '/bin/bash', 12:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:26 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:26 INFO - 'USER': 'cltbld', 12:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:26 INFO - 'XPC_FLAGS': '0x0', 12:16:26 INFO - 'XPC_SERVICE_NAME': '0', 12:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:26 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 12:16:26 INFO - Cleaning up... 12:16:26 INFO - Return code: 0 12:16:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:16: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')]} 12:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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')]} 12:16:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:16:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:16:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:26 INFO - 'HOME': '/Users/cltbld', 12:16:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:26 INFO - 'LOGNAME': 'cltbld', 12:16:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:26 INFO - 'MOZ_NO_REMOTE': '1', 12:16:26 INFO - 'NO_EM_RESTART': '1', 12:16:26 INFO - 'PAGER': '/bin/cat', 12:16:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:26 INFO - 'PWD': '/builds/slave/test', 12:16:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:26 INFO - 'SHELL': '/bin/bash', 12:16:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:26 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:26 INFO - 'USER': 'cltbld', 12:16:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:26 INFO - 'XPC_FLAGS': '0x0', 12:16:26 INFO - 'XPC_SERVICE_NAME': '0', 12:16:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:26 INFO - Downloading/unpacking psutil>=3.1.1 12:16:26 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 12:16:26 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 12:16:26 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 12:16:26 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 12:16:26 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 12:16:26 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 12:16:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:30 INFO - Installing collected packages: psutil 12:16:30 INFO - Running setup.py install for psutil 12:16:30 INFO - building 'psutil._psutil_osx' extension 12:16:30 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 12:16:30 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 12:16:30 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 12:16:30 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 12:16:30 INFO - building 'psutil._psutil_posix' extension 12:16:30 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 12:16:30 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:16:30 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:16:30 INFO - ^ 12:16:30 INFO - 1 warning generated. 12:16:30 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 12:16:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:16:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:16:30 INFO - Successfully installed psutil 12:16:30 INFO - Cleaning up... 12:16:31 INFO - Return code: 0 12:16:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:16:31 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')]} 12:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:31 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')]} 12:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:31 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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:31 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 12:16:31 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 12:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:31 INFO - 'HOME': '/Users/cltbld', 12:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:31 INFO - 'LOGNAME': 'cltbld', 12:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:31 INFO - 'MOZ_NO_REMOTE': '1', 12:16:31 INFO - 'NO_EM_RESTART': '1', 12:16:31 INFO - 'PAGER': '/bin/cat', 12:16:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:31 INFO - 'PWD': '/builds/slave/test', 12:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:31 INFO - 'SHELL': '/bin/bash', 12:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:31 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:31 INFO - 'USER': 'cltbld', 12:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:31 INFO - 'XPC_FLAGS': '0x0', 12:16:31 INFO - 'XPC_SERVICE_NAME': '0', 12:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:16:31 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 12:16:31 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 12:16:31 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 12:16:31 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 12:16:31 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 12:16:31 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 12:16:33 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:16:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:16:33 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:16:33 INFO - Installing collected packages: mozsystemmonitor 12:16:33 INFO - Running setup.py install for mozsystemmonitor 12:16:33 INFO - Successfully installed mozsystemmonitor 12:16:33 INFO - Cleaning up... 12:16:34 INFO - Return code: 0 12:16:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:16:34 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')]} 12:16:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:34 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')]} 12:16:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:34 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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:34 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 12:16:34 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 12:16:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:34 INFO - 'HOME': '/Users/cltbld', 12:16:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:34 INFO - 'LOGNAME': 'cltbld', 12:16:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:34 INFO - 'MOZ_NO_REMOTE': '1', 12:16:34 INFO - 'NO_EM_RESTART': '1', 12:16:34 INFO - 'PAGER': '/bin/cat', 12:16:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:34 INFO - 'PWD': '/builds/slave/test', 12:16:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:34 INFO - 'SHELL': '/bin/bash', 12:16:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:34 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:34 INFO - 'USER': 'cltbld', 12:16:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:34 INFO - 'XPC_FLAGS': '0x0', 12:16:34 INFO - 'XPC_SERVICE_NAME': '0', 12:16:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:34 INFO - Downloading/unpacking jsonschema==2.5.1 12:16:34 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 12:16:34 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 12:16:34 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 12:16:34 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 12:16:34 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 12:16:34 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 12:16:36 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:16:36 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:37 INFO - Downloading functools32-3.2.3-2.tar.gz 12:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:16:37 INFO - warning: no files found matching '*.txt' 12:16:37 INFO - no previously-included directories found matching 'build' 12:16:37 INFO - no previously-included directories found matching 'dist' 12:16:37 INFO - no previously-included directories found matching '.git*' 12:16:37 INFO - Installing collected packages: jsonschema, functools32 12:16:37 INFO - Running setup.py install for functools32 12:16:37 INFO - warning: no files found matching '*.txt' 12:16:37 INFO - no previously-included directories found matching 'build' 12:16:37 INFO - no previously-included directories found matching 'dist' 12:16:37 INFO - no previously-included directories found matching '.git*' 12:16:37 INFO - Successfully installed jsonschema functools32 12:16:37 INFO - Cleaning up... 12:16:37 INFO - Return code: 0 12:16:37 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:16: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')]} 12:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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')]} 12:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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', '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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:16: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 functools32==3.2.3-2 12:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:37 INFO - 'HOME': '/Users/cltbld', 12:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:37 INFO - 'LOGNAME': 'cltbld', 12:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:37 INFO - 'MOZ_NO_REMOTE': '1', 12:16:37 INFO - 'NO_EM_RESTART': '1', 12:16:37 INFO - 'PAGER': '/bin/cat', 12:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:37 INFO - 'PWD': '/builds/slave/test', 12:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:37 INFO - 'SHELL': '/bin/bash', 12:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:37 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:37 INFO - 'USER': 'cltbld', 12:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:37 INFO - 'XPC_FLAGS': '0x0', 12:16:37 INFO - 'XPC_SERVICE_NAME': '0', 12:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:37 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:16:37 INFO - Cleaning up... 12:16:37 INFO - Return code: 0 12:16:37 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:16: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')]} 12:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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')]} 12:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16: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', '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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:16: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 blobuploader==1.2.4 12:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:37 INFO - 'HOME': '/Users/cltbld', 12:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:37 INFO - 'LOGNAME': 'cltbld', 12:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:37 INFO - 'MOZ_NO_REMOTE': '1', 12:16:37 INFO - 'NO_EM_RESTART': '1', 12:16:37 INFO - 'PAGER': '/bin/cat', 12:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:37 INFO - 'PWD': '/builds/slave/test', 12:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:37 INFO - 'SHELL': '/bin/bash', 12:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:37 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:37 INFO - 'USER': 'cltbld', 12:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:37 INFO - 'XPC_FLAGS': '0x0', 12:16:37 INFO - 'XPC_SERVICE_NAME': '0', 12:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:37 INFO - Downloading/unpacking blobuploader==1.2.4 12:16:37 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 12:16:37 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 12:16:37 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 12:16:37 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 12:16:37 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 12:16:37 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 12:16:40 INFO - Downloading blobuploader-1.2.4.tar.gz 12:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:16:40 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:16:40 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 12:16:40 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 12:16:40 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 12:16:40 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 12:16:40 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 12:16:40 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 12:16:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:16:41 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:41 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:41 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:16:41 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:16:41 INFO - Downloading docopt-0.6.1.tar.gz 12:16:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:16:41 INFO - Installing collected packages: blobuploader, requests, docopt 12:16:41 INFO - Running setup.py install for blobuploader 12:16:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:16:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:16:41 INFO - Running setup.py install for requests 12:16:42 INFO - Running setup.py install for docopt 12:16:42 INFO - Successfully installed blobuploader requests docopt 12:16:42 INFO - Cleaning up... 12:16:42 INFO - Return code: 0 12:16:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:42 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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:42 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 12:16:42 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 12:16:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:42 INFO - 'HOME': '/Users/cltbld', 12:16:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:42 INFO - 'LOGNAME': 'cltbld', 12:16:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:42 INFO - 'MOZ_NO_REMOTE': '1', 12:16:42 INFO - 'NO_EM_RESTART': '1', 12:16:42 INFO - 'PAGER': '/bin/cat', 12:16:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:42 INFO - 'PWD': '/builds/slave/test', 12:16:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:42 INFO - 'SHELL': '/bin/bash', 12:16:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:42 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:42 INFO - 'USER': 'cltbld', 12:16:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:42 INFO - 'XPC_FLAGS': '0x0', 12:16:42 INFO - 'XPC_SERVICE_NAME': '0', 12:16:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:42 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-wka2If-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:42 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-JGHC25-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-mDeSKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-dHBfHz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-622qhq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-AGubhJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-es538x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-IdYu8x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-RCCAw3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-eR301H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:43 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-t_oO6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:44 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-BmO2dG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:44 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-6RJgsZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:44 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-2CD1xy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:44 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-9xpy7V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:44 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-pkbh8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:44 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:44 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-5tYOUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:44 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:16:44 INFO - Running setup.py install for manifestparser 12:16:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:16:44 INFO - Running setup.py install for mozcrash 12:16:44 INFO - Running setup.py install for mozdebug 12:16:45 INFO - Running setup.py install for mozdevice 12:16:45 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Running setup.py install for mozfile 12:16:45 INFO - Running setup.py install for mozhttpd 12:16:45 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Running setup.py install for mozinfo 12:16:45 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Running setup.py install for mozInstall 12:16:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:16:45 INFO - Running setup.py install for mozleak 12:16:45 INFO - Running setup.py install for mozlog 12:16:46 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:16:46 INFO - Running setup.py install for moznetwork 12:16:46 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:16:46 INFO - Running setup.py install for mozprocess 12:16:46 INFO - Running setup.py install for mozprofile 12:16:46 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:16:46 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:16:46 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:16:46 INFO - Running setup.py install for mozrunner 12:16:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:16:46 INFO - Running setup.py install for mozscreenshot 12:16:46 INFO - Running setup.py install for moztest 12:16:47 INFO - Running setup.py install for mozversion 12:16:47 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:16:47 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:16:47 INFO - Cleaning up... 12:16:47 INFO - Return code: 0 12:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:47 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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:47 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 12:16:47 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 12:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:47 INFO - 'HOME': '/Users/cltbld', 12:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:47 INFO - 'LOGNAME': 'cltbld', 12:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:47 INFO - 'MOZ_NO_REMOTE': '1', 12:16:47 INFO - 'NO_EM_RESTART': '1', 12:16:47 INFO - 'PAGER': '/bin/cat', 12:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:47 INFO - 'PWD': '/builds/slave/test', 12:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:47 INFO - 'SHELL': '/bin/bash', 12:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:47 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:47 INFO - 'USER': 'cltbld', 12:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:47 INFO - 'XPC_FLAGS': '0x0', 12:16:47 INFO - 'XPC_SERVICE_NAME': '0', 12:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:16:47 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ZEX3Hp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:16:47 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)) 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:16:47 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-daNn3d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:16:47 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-GpUM11-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:16:47 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)) 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:16:47 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-jb7oRa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:16:47 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-xOvBos-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-0ZhzSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-Pn0goj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-1oRCZD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-3UBkkP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-bNqhCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-B0yytZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-U230Z_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:16:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-yzaR6d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:16:48 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)) 12:16:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:16:48 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-ZQG1AJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-oiOkJz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:16:49 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)) 12:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:16:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-uaYwUR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:16:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:16:49 INFO - Running setup.py (path:/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/pip-U10Joh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:16:49 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)) 12:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:16:49 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)) 12:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:49 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:16:49 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:16: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 12:16: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 12:16: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 12:16: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 12:16: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 12:16: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 12:16:51 INFO - Downloading blessings-1.6.tar.gz 12:16:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:16:51 INFO - Installing collected packages: blessings 12:16:51 INFO - Running setup.py install for blessings 12:16:52 INFO - Successfully installed blessings 12:16:52 INFO - Cleaning up... 12:16:52 INFO - Return code: 0 12:16:52 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:16:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:52 INFO - Reading from file tmpfile_stdout 12:16:52 INFO - Current package versions: 12:16:52 INFO - blessings == 1.6 12:16:52 INFO - blobuploader == 1.2.4 12:16:52 INFO - docopt == 0.6.1 12:16:52 INFO - functools32 == 3.2.3-2 12:16:52 INFO - jsonschema == 2.5.1 12:16:52 INFO - manifestparser == 1.1 12:16:52 INFO - mozInstall == 1.12 12:16:52 INFO - mozcrash == 0.17 12:16:52 INFO - mozdebug == 0.1 12:16:52 INFO - mozdevice == 0.48 12:16:52 INFO - mozfile == 1.2 12:16:52 INFO - mozhttpd == 0.7 12:16:52 INFO - mozinfo == 0.9 12:16:52 INFO - mozleak == 0.1 12:16:52 INFO - mozlog == 3.4 12:16:52 INFO - moznetwork == 0.27 12:16:52 INFO - mozprocess == 0.23 12:16:52 INFO - mozprofile == 0.28 12:16:52 INFO - mozrunner == 6.12 12:16:52 INFO - mozscreenshot == 0.1 12:16:52 INFO - mozsystemmonitor == 0.3 12:16:52 INFO - moztest == 0.7 12:16:52 INFO - mozversion == 1.4 12:16:52 INFO - psutil == 3.1.1 12:16:52 INFO - requests == 1.2.3 12:16:52 INFO - wsgiref == 0.1.2 12:16:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:53 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 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:53 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 12:16:53 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 12:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:53 INFO - 'HOME': '/Users/cltbld', 12:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:53 INFO - 'LOGNAME': 'cltbld', 12:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:53 INFO - 'MOZ_NO_REMOTE': '1', 12:16:53 INFO - 'NO_EM_RESTART': '1', 12:16:53 INFO - 'PAGER': '/bin/cat', 12:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:53 INFO - 'PWD': '/builds/slave/test', 12:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:53 INFO - 'SHELL': '/bin/bash', 12:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:53 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:53 INFO - 'USER': 'cltbld', 12:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:53 INFO - 'XPC_FLAGS': '0x0', 12:16:53 INFO - 'XPC_SERVICE_NAME': '0', 12:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 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)) 12:16:53 INFO - Cleaning up... 12:16:53 INFO - Return code: 0 12:16:53 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:16:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:16:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:16:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:16:53 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10bc8c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bc02570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fef39d6f620>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, '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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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 12:16:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:16:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:16:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:16:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:16:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:16:53 INFO - 'HOME': '/Users/cltbld', 12:16:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:16:53 INFO - 'LOGNAME': 'cltbld', 12:16:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:16:53 INFO - 'MOZ_NO_REMOTE': '1', 12:16:53 INFO - 'NO_EM_RESTART': '1', 12:16:53 INFO - 'PAGER': '/bin/cat', 12:16:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:16:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:16:53 INFO - 'PWD': '/builds/slave/test', 12:16:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:16:53 INFO - 'SHELL': '/bin/bash', 12:16:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:16:53 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:16:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:16:53 INFO - 'USER': 'cltbld', 12:16:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:16:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:16:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:16:53 INFO - 'XPC_FLAGS': '0x0', 12:16:53 INFO - 'XPC_SERVICE_NAME': '0', 12:16:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:16:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:16:54 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:16:54 INFO - Cleaning up... 12:16:54 INFO - Return code: 0 12:16:54 INFO - Running post-action listener: _resource_record_post_action 12:16:54 INFO - Running post-action listener: _start_resource_monitoring 12:16:54 INFO - Starting resource monitoring. 12:16:54 INFO - [mozharness: 2016-09-29 19:16:54.200488Z] Finished create-virtualenv step (success) 12:16:54 INFO - [mozharness: 2016-09-29 19:16:54.201516Z] Running install step. 12:16:54 INFO - Running pre-action listener: _resource_record_pre_action 12:16:54 INFO - Running main action method: install 12:16:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:16:54 INFO - Reading from file tmpfile_stdout 12:16:54 INFO - Detecting whether we're running mozinstall >=1.0... 12:16:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:16:54 INFO - Reading from file tmpfile_stdout 12:16:54 INFO - Output received: 12:16:54 INFO - Usage: mozinstall [options] installer 12:16:54 INFO - Options: 12:16:54 INFO - -h, --help show this help message and exit 12:16:54 INFO - -d DEST, --destination=DEST 12:16:54 INFO - Directory to install application into. [default: 12:16:54 INFO - "/builds/slave/test"] 12:16:54 INFO - --app=APP Application being installed. [default: firefox] 12:16:54 INFO - mkdir: /builds/slave/test/build/application 12:16:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:16:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 12:17:29 INFO - Reading from file tmpfile_stdout 12:17:29 INFO - Output received: 12:17:29 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:17:29 INFO - Running post-action listener: _resource_record_post_action 12:17:29 INFO - [mozharness: 2016-09-29 19:17:29.266407Z] Finished install step (success) 12:17:29 INFO - [mozharness: 2016-09-29 19:17:29.266568Z] Running run-tests step. 12:17:29 INFO - Running pre-action listener: _resource_record_pre_action 12:17:29 INFO - Running main action method: run_tests 12:17:29 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:17:29 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:17:29 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:17:29 INFO - 2016-09-29 12:17:29.281 screenresolution[1913:9306] starting screenresolution argv=screenresolution get 12:17:29 INFO - 2016-09-29 12:17:29.283 screenresolution[1913:9306] Display 0: 1600x1200x32@60 12:17:29 INFO - 2016-09-29 12:17:29.289 screenresolution[1914:9308] starting screenresolution argv=screenresolution list 12:17:29 INFO - Available Modes on Display 0 12:17:29 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:17:29 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:17:29 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:17:29 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:17:29 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:17:29 INFO - Intel Iris: 12:17:29 INFO - Chipset Model: Intel Iris 12:17:29 INFO - Type: GPU 12:17:29 INFO - Bus: Built-In 12:17:29 INFO - VRAM (Dynamic, Max): 1536 MB 12:17:29 INFO - Vendor: Intel (0x8086) 12:17:29 INFO - Device ID: 0x0a2e 12:17:29 INFO - Revision ID: 0x0009 12:17:29 INFO - Displays: 12:17:29 INFO - Display: 12:17:29 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:17:29 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:17:29 INFO - Display Serial Number: 1600x1200 60 12:17:29 INFO - Main Display: Yes 12:17:29 INFO - Mirror: Off 12:17:29 INFO - Online: Yes 12:17:29 INFO - Rotation: Supported 12:17:29 INFO - Return code: 0 12:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:17:29 INFO - Python 2.7.3 12:17:29 INFO - Return code: 0 12:17:29 INFO - grabbing minidump binary from tooltool 12:17:29 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')]} 12:17:29 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 0x10baa0d30>, '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 0x10b8c6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ba98b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:17:29 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 12:17:29 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 12:17:29 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 12:17:29 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:17:29 INFO - Return code: 0 12:17:29 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:17:29 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:17:29 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:17:29 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:17:29 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:17:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 12:17:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0094 --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 12:17:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.9BXDKLd5A0/Render', 12:17:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:29 INFO - 'HOME': '/Users/cltbld', 12:17:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:29 INFO - 'LOGNAME': 'cltbld', 12:17:29 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:17:29 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:17:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:29 INFO - 'MOZ_NO_REMOTE': '1', 12:17:29 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:17:29 INFO - 'NO_EM_RESTART': '1', 12:17:29 INFO - 'PAGER': '/bin/cat', 12:17:29 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:29 INFO - 'PWD': '/builds/slave/test', 12:17:29 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:17:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:29 INFO - 'SHELL': '/bin/bash', 12:17:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.WQZfJDFQPf/Listeners', 12:17:29 INFO - 'TMPDIR': '/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/', 12:17:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:29 INFO - 'USER': 'cltbld', 12:17:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:29 INFO - 'XPC_FLAGS': '0x0', 12:17:29 INFO - 'XPC_SERVICE_NAME': '0', 12:17:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:29 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0094', '--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 12:17:29 INFO - mozversion application_buildid: 20160929100439 12:17:29 INFO - mozversion application_changeset: 1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522 12:17:29 INFO - mozversion application_display_name: Nightly 12:17:29 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:17:29 INFO - mozversion application_name: Firefox 12:17:29 INFO - mozversion application_remotingname: firefox 12:17:29 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 12:17:29 INFO - mozversion application_vendor: Mozilla 12:17:29 INFO - mozversion application_version: 52.0a1 12:17:29 INFO - mozversion platform_buildid: 20160929100439 12:17:29 INFO - mozversion platform_changeset: 1723fbc9d9d88a5fbf1c57cdd1e226e36f04c522 12:17:29 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 12:17:29 INFO - mozversion platform_version: 52.0a1 12:17:29 INFO - using testdate: 1475176649 12:17:29 INFO - actual date: 1475176649 12:17:29 INFO - starting webserver on 'localhost:49811' 12:17:29 INFO - SUITE-START | Running 2 tests 12:17:29 INFO - TEST-START | dromaeo_css 12:17:29 INFO - operating with platform_type : mac_ 12:17:29 INFO - Initialising browser for dromaeo_css test... 12:17:29 INFO - TEST-INFO | started process 1924 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpbz5NYn/profile http://localhost:49811/getInfo.html) 12:17:32 INFO - PROCESS | 1924 | __metrics Screen width/height:1600/1200 12:17:32 INFO - PROCESS | 1924 | colorDepth:24 12:17:32 INFO - PROCESS | 1924 | Browser inner width/height: 1024/689 12:17:32 INFO - PROCESS | 1924 | __metrics 12:17:32 INFO - PROCESS | 1924 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:17:32 INFO - PROCESS | 1924 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:17:33 INFO - TEST-INFO | 1924: exit 0 12:17:33 INFO - Browser initialized. 12:17:33 INFO - Running cycle 1/1 for dromaeo_css test... 12:17:33 INFO - TEST-INFO | started process 1926 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpbz5NYn/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:17:45 INFO - PROCESS | 1926 | RSS: Main: 236318720 12:17:45 INFO - PROCESS | 1926 | 12:18:36 INFO - PROCESS | 1926 | 1475176716900 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 12:20:48 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49811/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49811/tests/dromaeo/cssquery-ext.html) 12:20:49 INFO - PROCESS | 1926 | RSS: Main: 375775232 12:20:49 INFO - PROCESS | 1926 | 12:23:52 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49811/tests/dromaeo/cssquery-ext.html (next: http://localhost:49811/tests/dromaeo/cssquery-jquery.html) 12:23:53 INFO - PROCESS | 1926 | RSS: Main: 372158464 12:23:53 INFO - PROCESS | 1926 | 12:26:55 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49811/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49811/tests/dromaeo/cssquery-mootools.html) 12:26:56 INFO - PROCESS | 1926 | RSS: Main: 339120128 12:26:56 INFO - PROCESS | 1926 | 12:29:58 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49811/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49811/tests/dromaeo/cssquery-prototype.html) 12:29:59 INFO - PROCESS | 1926 | RSS: Main: 356974592 12:29:59 INFO - PROCESS | 1926 | 12:33:02 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49811/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49811/tests/dromaeo/cssquery-yui.html) 12:33:03 INFO - PROCESS | 1926 | RSS: Main: 325812224 12:33:03 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | Cycle 1(1): loaded http://localhost:49811/tests/dromaeo/cssquery-yui.html (next: http://localhost:49811/tests/dromaeo/cssquery-dojo.html) 12:36:06 INFO - PROCESS | 1926 | RSS: Main: 409436160 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | __start_tp_report 12:36:06 INFO - PROCESS | 1926 | _x_x_mozilla_page_load 12:36:06 INFO - PROCESS | 1926 | _x_x_mozilla_page_load_details 12:36:06 INFO - PROCESS | 1926 | |i|pagename|runs| 12:36:06 INFO - PROCESS | 1926 | |0;dojo.html;2769.5568709006557;3341.1480072581494;3351.966480335196;3381.05330507458;3411.4200585900394;5588.972055139719;5653.1520271959225;5743.081107022877;5744.368119506851;5749.79875704351;5677.943220567793;5681.460261275182;5757.68332741699;5783.306003279602;5812.070068788984;5021.824236151735;5133.204353325241;5136.563392111675;5169.431362550135;5214.973925130369;4456.509783923766;5236.973815130938;5281.973590132044;5288.814891478799;5308.6814791112465;5237.397699264595;5256.710880901549;5298.4966428189455;5300.893982120375;5317.867053323659;4237.4491316129015;5166;5168.121419358718;5176.430592634807;5212;5077.213031980049;5104.438511763703;5132.153194722866;5148.665336753136;5205.375354957391;5674.148877668383;5682.886342273173;5697.259356283656;5699.287589051369;5781.942180578225;6112.449879510821;6126.36500017443;6129.453906865831;6183.280465569331;6232.626042437469;6174.197354343952;6234.844128896814;6294.118823364733;6305.148804911324;6316.557840951135;5773.1051686988585;5775.27809023872;5779.306483222041;5790.942090579125;5832;6165.660888651078;6258.217722784652;6297.811065668037;6304.211973503312;6313.084602732578;6132.141360525245;6135.32511423743;6135.747419720469;6137.662428566382;6142.262928448526;6263.749450022039;6266.091416744546;6270.655113968684;6271.592346497463;6277.591956522811;6032.065029920369;6122.816315510542;6201.844953876189;6206.472449841724;6215.223097112861;6113.658745237643;6169.784057558054;6179.351168127372;6214.627122372672;6271.247450305992;4871.80512779492;4875.585575226146;4894.730789806524;4911.729854857946;4929.408470983433;4235.25882970479;4423.491298500714;4428.911421771547;4444.688871779009;4466.330050492452;5655.151727240865;5674.375818659944;5741.110127930177;5750.626209296466;5757.942420575824;6267.62394256346;6281.59169653971;6294.402031806971;6300.873982520324;6307.810765677037;5793.478586927196;5834.970825145932;5838.9124163137585;5839.182514447895;5839.474447299764;5279.340082489689;5300.496452836975;5339.893202136013;5371.086915224421;5379.919301210485;6219.129321894939;6229.470495007975;6245.718942647592;6316.242050953915;6320.5891617044745;6271.62370257786;6277.372262773869;6281.748730050746;6295.244570651551;6308.968455157695;6236.688165591793;6247.937520624736;6281.057841323838;6283.3402492737605;6284.842878928063;6094.2077529920825;6103.08453731926;6110.923300256584;6155.599886007395;6200.534959878118;6267.96866015667;6275.435210831047;6280.1207830902795;6285.685715714288;6297.2128483939505;6072.574919755752;6099.207103076572;6113.694315284129;6117.235345581802;6140.3552626975315;5137.51193636604;5935.881282374242;6010.489108425658;6039.939600604113;6067.757289708362;5870.589058765845;5905.320888097752;5934.673592952429;5947.791827286197;5960.284765827955;6100.328963813889;6105.786297479567;6106.267247930099;6108.08378743192;6128.662923539248;5769.624974376652;5814.331351894417;5852.94147058524;5885.911711324248;5931.525477961839 12:36:06 INFO - PROCESS | 1926 | |1;ext.html;476.3949783774605;486.4114421549924;509.14463700982344;512.3314075131954;555.0730280431678;30736;40286.798566007164;41344.37983430251;41369.7931510342;41437.72092534913;41898.79050604743;42105.99999999992;42274.15451690972;42346.729598112026;42391.57608423915;8634.611442485104;9587;9797.167240784542;9801.313908026441;9804.215662747;8693.869591956125;11726.941365293162;12027.45876435558;12048.879511204863;12102;11938.22401543897;11981.820272695917;12003.459844307026;12013.657336819324;12041.217320874159;11865.940670296679;11971.880281197164;11974.22167559106;11975.940120299343;12016.93991530041;11610.6516804496;11704.590339338167;11711.063114950783;11712.355820429873;11717.765644687059;39184;39506.60493395058;39550;39622.80188599038;39627.20745585101;3185.824779637119;3195.2491164576304;3561.4123669594487;3876.2441323941844;3907.8436862525464;24910.626340604904;29856.85071574628;31115.84442077798;31463.52804707931;31699.52450713241;2757.7518023377993;3189.8086114833186;3249.301400198945;3273.623533293678;3294.8352582371026;458762;474782;481090;482298;488832.5558372221;57022.42977570254;57228.71385643045;57300.42699573034;57353;57391;34282.314353713286;34890;34910.82544587261;58572.41427585755;58866.82266354649;15814.520805422788;15966.920165399331;15975.680486390207;15983.44057957966;15994.600134996486;11047.72380690489;11293.435328233556;11297.943510282561;12224.633261002184;12333.013358931265;16099.919500402657;16205;16243;18350;18812.341568045053;7219.592179525047;7371.9262807372315;7429.294217049372;7494.587797671072;7555;816.8243827577099;827.4084029918637;905.4310973884426;1021.7496713305288;1066.6053560182781;28470;28836.42327153445;29431.34480078138;29498.410031799674;29677.961271355398;971.9745668319849;1033.7105610429091;1039.7608550033383;1120.2942146447685;1176.5117476247262;14538.200398977946;14977.625559361104;15070.547883563511;15292.388304467697;15295.923520382326;660034;665098;666359;666430;666844;56102.719486402304;56320.87358252812;56562;56576.43423565794;56749.71625141848;1035.3891204189565;1206.51739304279;1242.714175739585;1339.7990301454665;1359.7824348104455;29012.83948642115;30380.544291836082;30664.540031899985;30791.384172316877;31308.68691313149;47113.764431177624;47653.76173119112;47717.761411192725;47839.521604783506;47979;565248;569121;570660;571955;573027;26386.86806565955;26890;27134.829213663255;27437.725622743517;27742.583861241696;45879;46294.53705462902;46600.7669961648;46741.29888051614;46992.530074698814;37763.81118094392;38054.429183562825;38260.617393825705;38292.61707382891;38820;37667.81166094152;37836.62163378331;37857;38795.61204388033;39082.60917390903;37126.814365927996;38051.61948380592;38427.42358864563;38464.230715384976;38512.6148738509;28383.14850554487;30904.227394315323;32234.71061157621;34302.97091087271;34331;19523.926184059444;24186;24993.375165621004;25007.874960625082;25523.87238063798 12:36:06 INFO - PROCESS | 1926 | |2;jquery.html;349.4531058892831;368.59270506090786;370.8090333478259;378.45124569374406;398.1817365314278;15069.095854248753;15211.695766084686;16207;20728.859912704796;20812.68780968283;19210.711839322423;19890.20439182431;20276.391708248775;20454.590908181828;21051.05270262838;5005.799768009293;5932.3474417814;6075;6187.845303867394;6218.8445288867915;6026.8794624107495;6078.118672792442;6138.311964209834;6160.1683772690785;6161.29145148307;5760.798372056979;6005.039193729004;6073.7874174403905;6081.270247570275;6097;6028.156058151862;6071.939280607182;6072.939270607281;6084.665343406111;6086.45221930024;3703.129764505339;5209.1404918188455;5254.949010197975;5255.395629502618;5270.736463176826;25843.61234581483;25925.83333749986;26007.479850402888;26230.34424139412;26562.937482500678;2150.827933765295;2152.45112496314;2160.8595441296425;2173.7065496157975;2205.878676672782;14679.853201467955;15736.449224277096;15894.841051589452;15960.920195399065;16237.431689890915;1812.5559237986645;1898.155320882208;1903.8857668539931;1909.6467153576632;1945.7956914523913;112167.87832121656;120534;122415;124022;124244;8929.24101451371;10169.898301017043;10408.687739367831;10554.525046372779;10892.183086268562;13432.865671343357;13463.192208467523;13467.865321346857;13477.932610336886;13495;8078;8367.205115514016;8379.45533540318;8395.580220988926;8533.871991920127;8351.123132071167;8354.665813367397;8545.359098067669;8546.31629469641;8553.615087321086;6415.582987105823;6544.1349215473365;6559.573627714183;6569.967150164314;6578.835529111715;1443.978340324896;1580.8498192671677;1736.5658585353663;1744.63918143848;1822.82683145101;880.9867851982225;912.6760000199833;930.330162283155;940.0129863643148;965.9806803864024;20913.26803561868;22212.444688882908;22292.554148917254;22389.776102239095;22417.551648966928;754.9509281896659;889.3685483306924;909.9636014559476;948.4025064209504;1040.1782591752583;10332.948335258427;11492.712682183012;11614.6515604532;11775.293482391084;11837.763244735057;504493;506780;511871;576523;602722.9863850741;92882;117106;123703.381483092;133379;139530;891.2914233184633;938.2119020023214;974.7952929884661;979.2410881566842;1005.0100650859044;13415.798763018367;14328.641783955067;17343.78593498443;18931.148098335332;18986.335478258196;51716.965660687325;52944.235018848485;54033.459665404414;54464.91070178495;55340.44659553353;58931.410685892595;76820.2317976813;78028;79776.20783272557;80270;7632.694692212472;7800.804979875548;7857.135715071223;7925.881111783443;7938.761837144895;10190.490475476048;12297.815532766837;12418.068644851854;12574.811377829523;12610.49558017669;10505.212109092;10783.299085559414;10793.460326983777;10900.127989760958;10909.563617455531;10455.634052808115;10539.262251642618;10657.41384223844;10757.9462102689;10782.299150555189;11026.944865275622;11124.833127503256;11165.497552610315;11202.49588768491;11272.380019099028;7754.883676744967;7804.6878124874365;7823.452358335089;7867.17394673551;7898.2101789822855;9855.605775769176;10466.685999419726;10669.41318227505;10983.61557345489;11102.888971110186 12:36:06 INFO - PROCESS | 1926 | |3;mootools.html;1356.830396200475;1481.3704175725318;1617.5228307649243;1782.84845788108;1835.3576248313084;8347.20701533354;8829.955850220747;8846.911530884689;8851.91148088519;8867.423617464869;7720.536767793937;8042.276195142436;8049.959750201256;8408.075111737704;8653.221210091091;2338.5206032763285;2367.8934447949796;2368.2421625079983;2373.762623737621;2376.0970831084164;2339.695839540866;2345.483993521431;2375.9168429104984;2376.988115059431;2378.0606660369144;2285.714285714286;2294.908203671851;2309.5265470578493;2370;2375.9406014849596;2316.525112351964;2340.6840076589615;2341.438054866837;2346.3195673254736;2349.5066036132434;2326.685897403846;2336.7896889280046;2341.6370462578157;2342.0397636968933;2347.730011048717;7547.207543207994;8647.264982476498;8727.650893964234;8777.253933415668;8815.559222038937;5149.433562308143;5247.921281180747;5340.439253878326;5354.5180933715765;5358.4641535846495;3368.6799754023327;3501.24723184514;3573.428251479777;3575.0526110580718;3584.4623306503972;1005.2109094360892;1039.7244730146517;1040.2614143957799;1059;1061.7345663584103;11351.20541562091;11538.711532211679;11550.88449115515;11552.191346605741;11565.363904985223;4502.752348620792;4610.884727881829;4761.9047619048115;4790.401199850019;4793.195980351534;9805.313628046111;9810;9817.01829817027;9826.557804898654;9834.311598188202;5288.312519372457;5294.97352513235;5296.576273898079;5300.416954135042;5309.33633295838;4357.760323182192;4392.560743925582;4535.9773201133785;4712.59942904857;4866.245731911559;3835.9424608630893;3843.5387753469763;3959.5050618672667;3968.146848427602;4003.7597744135446;1263.0716423428773;1281.9615411537668;1285.736424033071;1296.1639742366121;1297.5199176304827;808.5754978636145;884.0319849764497;900.3967341880956;905.4702998745676;936.4708939449189;5712.742926568314;5754.395788442237;5926.288845338587;6116.969415152985;6245.437910587978;887.2768693514765;888.9466632002101;907.2651152566442;910.7723069232692;917.3349321741684;3882.941755873664;3912.9021774455296;3915;4018.618231268025;4046.109855831712;10235.232357573062;10723.89276107229;10731.692686072533;10738.248322617343;10739.355638661706;10643.308184967953;10669.893301067044;10683.198760093024;10683.358998460117;10709.410982396043;7578.583177925267;7900.841983160304;7939.20607939213;7946.404019698662;7984.520928744178;5513.255710479034;5820.912686309624;5856.765729370777;6004.339522652417;6067.393260673897;9240.722778316662;9481.383710058824;9539.570719317786;9586.568604412678;9609.9039009609;8440.66237350499;9325.487098209393;9739.172170365599;10181.083702466814;10191;6314.652694101868;7429.888551671621;7537.54774713519;7579.5073320234005;7636.236376362319;8216.712415065444;8264.958675206586;8279.83440331178;8294.502329860228;8307.003159620885;7274.381677557255;7391.778246652609;7431.702731890664;7489.962550187214;7499;6181.9690901547;7238.131424229126;7259;7278;7330.890036649348;6870.690818913261;6915.446764258948;6999.825004374931;7002.964985175042;7011.228764835966;4935.925961110658;4985.601151907911;5017.4731653175895;5046.09170349326;5119.897602048012;4942.382202224722;4977.303177555217;5006.474320196473;5172.948270517247;5212.009718153538 12:36:06 INFO - PROCESS | 1926 | |4;prototype.html;265.7263019090337;289.53818659238505;305.7401208972374;308.2601755786113;312.507800214662;5125.692458452486;5157.071727089123;5376.919346209805;5412.810551630694;5450.836474905749;4625.722456652603;4681.83613573525;5038.548594212966;5229.712365819878;5232.534890230732;3324.75064370172;3354.899353019401;3982.820773065219;4041.6968727345493;4045.777482238476;3701.482392219191;3723.8324275407535;3873.7288389812725;4060.634542891139;4063.8577649782264;3997.72015958883;4023.517177938637;4028.47629808126;4039.313316736147;4051.311277082903;3538.6992105671047;3993.8602148924792;3998.7403967750065;4019.974906398879;4059.939100913489;2886.942261154765;3473.913152171216;3489.36000079962;3518.665726755954;3520.2542017226606;4151.83392664293;5167.767450464738;5203.687778733288;5205.401378841444;5255.684658920439;4560.042391097873;4567.510706664875;4585.747783871886;4595.012072404415;4667.4632417272105;5623.831285061455;5632.944280118309;5647.632903861236;5667;5692.544596432275;3562.4300111982216;3777.0557360659836;3789.745101664505;3826.1582451860822;3866.903327416809;4570.611498022672;4714.95285047152;5757.165211044375;5895.55334536167;5940.227770389865;3774.999625099351;3820.3123437781464;3835.2521258354354;3876.5154355705536;3881.165549406891;3486.877959271413;3658.652428019334;3897.707671924617;3906.511686039245;3936.3111455495173;4525.52481989393;4526.456825181;4533.365328853963;4563.54364563541;4599.7470139142;4718.8584342469785;5551.750171242223;5580.665160090408;5589.74846131925;5605.495505404533;1097.5664612478026;1248.744007478483;1290.3419256179416;1298.8441387033618;1319.8878095362002;870.4211699220075;898.8180542586477;900.8783814185001;905.212465155306;909.9535534135797;5209.479052094836;5218.452062533455;5225.16397376418;5244.9737751310995;5267.920076384332;4405.414050941658;4814.855554333376;5001.974990125026;5464.699441530674;5808.361080281165;5120.667156634881;5143.3570803649545;5166.225066240019;5188.351456067991;5246.606504512177;2474.3421907322136;2496.5270490410744;2505.924822255335;2513.951129829183;2517.949641007206;4535.367267783596;4875.123677030569;4910.361652985089;5587.217789509472;6044.516438684895;5931.851703707442;5944.316403613553;5945;5963.4931030862;5968.432998865101;5312.707801070901;5765.798197063083;6000.249968753906;6068.423499767515;6082.665453400017;5382.973085134549;5506.944930550643;5752.0509115997065;5773.942260577341;5832.329282132439;4833.275008748716;5291.76187071575;5706.544831068051;5728.6201141325155;5764.654120752768;4900.854103191706;5807.535397168301;5994.220751302303;6014.917314883188;6051.062085376773;3816.1985982943893;3834.712396570324;3857.594952529944;3875.0818344919444;3910.7904034220574;4813.831515896927;5336.252924590638;5759.523404935918;5806.709664516673;5844.240248767803;4326.637109310557;5402.216678581505;5635.8591035224445;5695.715214239354;5698.800541981177;4772.880677983078;4895.583875370491;5638.407967163389;5643.887122257614;5686.9715651421475;5233.1013395020955;5331.173668081454;5406.702151585482;5471.23402723627;5497.972510137423;5007.624428167975;5463.869091054413;5592.608517403905;5704.258446401941;5766.884662306814;4347.500037495728;4525.773711314355;5323.893522129459;5335.33308336458;5580.553555715452 12:36:06 INFO - PROCESS | 1926 | |5;yui.html;699.2552931128345;707.4057791455177;739.3641468337225;771.6681826814471;853.9573021348933;14895.851041489583;15355.462558810445;15709.700962797811;15733.05601663901;16181.514554563353;14447.205403702792;14453.710925781477;14615.561533153996;14622.853771462282;14635.707285854276;680.9250982391933;703.4829400390735;836.6527890925258;854.8033952190968;860.1183786618703;838.6435764799966;855.4054931822386;860.7848037990502;861.5735211070529;868.288003836854;907.6233363154283;908.6728777640077;908.8500397434449;910.4127837544779;910.4628269321132;709.9467539934526;728.719443014438;878.2885862451371;904.2043002158092;904.9321300902459;660.8480049588574;661.6856992928368;662.526293700002;663.2207156591035;666.3702800853196;15263.465778697804;15322.310496027705;15347.232638368148;15350.539483815392;15351.155686437241;13412.664683382896;13457.058005939589;13555;13673.931630341884;14129.929350353284;12163.5134594617;12330.116013455732;12418.068644851672;12478.875211247861;12948.611541653765;3804.5434547854434;4471.150481408555;4479.2609219478745;4520.841770538015;4559.544045595414;112768.43615781868;112784;113427;116764.41617791941;120680.39659801645;10572;10619.42490164887;10750.946245268724;10850.0777433919;11004.119670426348;21252;21292.467688307606;21302.78697213008;21310.467238319165;21315.29477641785;7452.217517160729;7561.546307221584;7643.9617801911745;7702.152763196044;7776.339011184113;4133.297339452301;4398.208322501916;4412.411531848532;4484.618807401407;4590.449146102423;3093.0256969054676;3309.7186739126964;3409.7272218222483;3522.682958533693;3892.163184915257;632.4434497642137;666.9566478178903;672.1530871102447;684.0525871667776;691.1084700736095;621.3724138620026;662.6057495789997;678.718331892269;680.2313385873931;681.3288910423229;9119.81760364789;9356.4386136832;9610.711678649652;9614.807703846023;9650.227981761442;514.5960421069442;587.0636335045681;673.5083389125965;679.78926532775;680.363859791097;4391.341298805205;4412.823487060546;4648.97675511627;4822.180229361017;4864.489228631013;97231;97356.53965190527;123028.53942921091;143749;153626;134861;135138;138292.30853845665;140273.5972640281;143298.28350858387;92295.53852230696;95013.14960551195;113944.43027784974;114156.42921785338;114610;7181.9227115930935;8049.315808156372;8913.777155571162;8963.327750418875;8986.415882967585;47583.15254881232;63496.68251658712;66060.56668182688;66212;66922.66154676977;90168;93757.5312123435;94553;96715;97761.02238977804;11531.654050378502;11684.532618695497;11756.059515238589;11783.469743861375;11988.280703157838;12801;12985;13039.148317449253;13154.144980576231;13607.251601162032;12043.75912481728;12113.69715757113;12171.02631789437;12257.32584707814;12461.813072803734;11672.649820505398;11889.82165267539;12091.818622720843;12209.755804884031;12315.93842030784;10139.695809125738;10769.676909692722;12067.939660301643;12141.453634586642;12263.141580089308;10216.693499195037;10316;10432.58269669235;10580.788384232119;10787.975142361463;10278.537465813906;10355.275130741076;10464.372137671764;10490.580376784843;10545.57817687284 12:36:06 INFO - PROCESS | 1926 | __end_tp_report 12:36:06 INFO - PROCESS | 1926 | __start_cc_report 12:36:06 INFO - PROCESS | 1926 | _x_x_mozilla_cycle_collect,1319 12:36:06 INFO - PROCESS | 1926 | __end_cc_report 12:36:06 INFO - PROCESS | 1926 | __startTimestamp1475177766558__endTimestamp 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | ------- Summary: start ------- 12:36:06 INFO - PROCESS | 1926 | Number of tests: 6 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | [#0] dojo.html Cycles:165 Average:5721.74 Median:5931.53 stddev:670.00 (11.3%) stddev-sans-first:630.76 12:36:06 INFO - PROCESS | 1926 | Values: 2769.6 3341.1 3352.0 3381.1 3411.4 5589.0 5653.2 5743.1 5744.4 5749.8 5677.9 5681.5 5757.7 5783.3 5812.1 5021.8 5133.2 5136.6 5169.4 5215.0 4456.5 5237.0 5282.0 5288.8 5308.7 5237.4 5256.7 5298.5 5300.9 5317.9 4237.4 5166.0 5168.1 5176.4 5212.0 5077.2 5104.4 5132.2 5148.7 5205.4 5674.1 5682.9 5697.3 5699.3 5781.9 6112.4 6126.4 6129.5 6183.3 6232.6 6174.2 6234.8 6294.1 6305.1 6316.6 5773.1 5775.3 5779.3 5790.9 5832.0 6165.7 6258.2 6297.8 6304.2 6313.1 6132.1 6135.3 6135.7 6137.7 6142.3 6263.7 6266.1 6270.7 6271.6 6277.6 6032.1 6122.8 6201.8 6206.5 6215.2 6113.7 6169.8 6179.4 6214.6 6271.2 4871.8 4875.6 4894.7 4911.7 4929.4 4235.3 4423.5 4428.9 4444.7 4466.3 5655.2 5674.4 5741.1 5750.6 5757.9 6267.6 6281.6 6294.4 6300.9 6307.8 5793.5 5835.0 5838.9 5839.2 5839.5 5279.3 5300.5 5339.9 5371.1 5379.9 6219.1 6229.5 6245.7 6316.2 6320.6 6271.6 6277.4 6281.7 6295.2 6309.0 6236.7 6247.9 6281.1 6283.3 6284.8 6094.2 6103.1 6110.9 6155.6 6200.5 6268.0 6275.4 6280.1 6285.7 6297.2 6072.6 6099.2 6113.7 6117.2 6140.4 5137.5 5935.9 6010.5 6039.9 6067.8 5870.6 5905.3 5934.7 5947.8 5960.3 6100.3 6105.8 6106.3 6108.1 6128.7 5769.6 5814.3 5852.9 5885.9 5931.5 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | [#1] ext.html Cycles:180 Average:69615.70 Median:19236.12 stddev:154024.60 (800.7%) stddev-sans-first:154369.21 12:36:06 INFO - PROCESS | 1926 | Values: 476.4 486.4 509.1 512.3 555.1 30736.0 40286.8 41344.4 41369.8 41437.7 41898.8 42106.0 42274.2 42346.7 42391.6 8634.6 9587.0 9797.2 9801.3 9804.2 8693.9 11726.9 12027.5 12048.9 12102.0 11938.2 11981.8 12003.5 12013.7 12041.2 11865.9 11971.9 11974.2 11975.9 12016.9 11610.7 11704.6 11711.1 11712.4 11717.8 39184.0 39506.6 39550.0 39622.8 39627.2 3185.8 3195.2 3561.4 3876.2 3907.8 24910.6 29856.9 31115.8 31463.5 31699.5 2757.8 3189.8 3249.3 3273.6 3294.8 458762.0 474782.0 481090.0 482298.0 488832.6 57022.4 57228.7 57300.4 57353.0 57391.0 34282.3 34890.0 34910.8 58572.4 58866.8 15814.5 15966.9 15975.7 15983.4 15994.6 11047.7 11293.4 11297.9 12224.6 12333.0 16099.9 16205.0 16243.0 18350.0 18812.3 7219.6 7371.9 7429.3 7494.6 7555.0 816.8 827.4 905.4 1021.7 1066.6 28470.0 28836.4 29431.3 29498.4 29678.0 972.0 1033.7 1039.8 1120.3 1176.5 14538.2 14977.6 15070.5 15292.4 15295.9 660034.0 665098.0 666359.0 666430.0 666844.0 56102.7 56320.9 56562.0 56576.4 56749.7 1035.4 1206.5 1242.7 1339.8 1359.8 29012.8 30380.5 30664.5 30791.4 31308.7 47113.8 47653.8 47717.8 47839.5 47979.0 565248.0 569121.0 570660.0 571955.0 573027.0 26386.9 26890.0 27134.8 27437.7 27742.6 45879.0 46294.5 46600.8 46741.3 46992.5 37763.8 38054.4 38260.6 38292.6 38820.0 37667.8 37836.6 37857.0 38795.6 39082.6 37126.8 38051.6 38427.4 38464.2 38512.6 28383.1 30904.2 32234.7 34303.0 34331.0 19523.9 24186.0 24993.4 25007.9 25523.9 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | [#2] jquery.html Cycles:180 Average:33232.78 Median:25966.66 stddev:90942.24 (350.2%) stddev-sans-first:91163.84 12:36:06 INFO - PROCESS | 1926 | Values: 349.5 368.6 370.8 378.5 398.2 15069.1 15211.7 16207.0 20728.9 20812.7 19210.7 19890.2 20276.4 20454.6 21051.1 5005.8 5932.3 6075.0 6187.8 6218.8 6026.9 6078.1 6138.3 6160.2 6161.3 5760.8 6005.0 6073.8 6081.3 6097.0 6028.2 6071.9 6072.9 6084.7 6086.5 3703.1 5209.1 5254.9 5255.4 5270.7 25843.6 25925.8 26007.5 26230.3 26562.9 2150.8 2152.5 2160.9 2173.7 2205.9 14679.9 15736.4 15894.8 15960.9 16237.4 1812.6 1898.2 1903.9 1909.6 1945.8 112167.9 120534.0 122415.0 124022.0 124244.0 8929.2 10169.9 10408.7 10554.5 10892.2 13432.9 13463.2 13467.9 13477.9 13495.0 8078.0 8367.2 8379.5 8395.6 8533.9 8351.1 8354.7 8545.4 8546.3 8553.6 6415.6 6544.1 6559.6 6570.0 6578.8 1444.0 1580.8 1736.6 1744.6 1822.8 881.0 912.7 930.3 940.0 966.0 20913.3 22212.4 22292.6 22389.8 22417.6 755.0 889.4 910.0 948.4 1040.2 10332.9 11492.7 11614.7 11775.3 11837.8 504493.0 506780.0 511871.0 576523.0 602723.0 92882.0 117106.0 123703.4 133379.0 139530.0 891.3 938.2 974.8 979.2 1005.0 13415.8 14328.6 17343.8 18931.1 18986.3 51717.0 52944.2 54033.5 54464.9 55340.4 58931.4 76820.2 78028.0 79776.2 80270.0 7632.7 7800.8 7857.1 7925.9 7938.8 10190.5 12297.8 12418.1 12574.8 12610.5 10505.2 10783.3 10793.5 10900.1 10909.6 10455.6 10539.3 10657.4 10757.9 10782.3 11026.9 11124.8 11165.5 11202.5 11272.4 7754.9 7804.7 7823.5 7867.2 7898.2 9855.6 10466.7 10669.4 10983.6 11102.9 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | [#3] mootools.html Cycles:180 Average:5628.16 Median:5161.19 stddev:3122.16 (60.5%) stddev-sans-first:3114.41 12:36:06 INFO - PROCESS | 1926 | Values: 1356.8 1481.4 1617.5 1782.8 1835.4 8347.2 8830.0 8846.9 8851.9 8867.4 7720.5 8042.3 8050.0 8408.1 8653.2 2338.5 2367.9 2368.2 2373.8 2376.1 2339.7 2345.5 2375.9 2377.0 2378.1 2285.7 2294.9 2309.5 2370.0 2375.9 2316.5 2340.7 2341.4 2346.3 2349.5 2326.7 2336.8 2341.6 2342.0 2347.7 7547.2 8647.3 8727.7 8777.3 8815.6 5149.4 5247.9 5340.4 5354.5 5358.5 3368.7 3501.2 3573.4 3575.1 3584.5 1005.2 1039.7 1040.3 1059.0 1061.7 11351.2 11538.7 11550.9 11552.2 11565.4 4502.8 4610.9 4761.9 4790.4 4793.2 9805.3 9810.0 9817.0 9826.6 9834.3 5288.3 5295.0 5296.6 5300.4 5309.3 4357.8 4392.6 4536.0 4712.6 4866.2 3835.9 3843.5 3959.5 3968.1 4003.8 1263.1 1282.0 1285.7 1296.2 1297.5 808.6 884.0 900.4 905.5 936.5 5712.7 5754.4 5926.3 6117.0 6245.4 887.3 888.9 907.3 910.8 917.3 3882.9 3912.9 3915.0 4018.6 4046.1 10235.2 10723.9 10731.7 10738.2 10739.4 10643.3 10669.9 10683.2 10683.4 10709.4 7578.6 7900.8 7939.2 7946.4 7984.5 5513.3 5820.9 5856.8 6004.3 6067.4 9240.7 9481.4 9539.6 9586.6 9609.9 8440.7 9325.5 9739.2 10181.1 10191.0 6314.7 7429.9 7537.5 7579.5 7636.2 8216.7 8265.0 8279.8 8294.5 8307.0 7274.4 7391.8 7431.7 7490.0 7499.0 6182.0 7238.1 7259.0 7278.0 7330.9 6870.7 6915.4 6999.8 7003.0 7011.2 4935.9 4985.6 5017.5 5046.1 5119.9 4942.4 4977.3 5006.5 5172.9 5212.0 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | [#4] prototype.html Cycles:180 Average:4485.09 Median:5023.09 stddev:1407.42 (28.0%) stddev-sans-first:1375.27 12:36:06 INFO - PROCESS | 1926 | Values: 265.7 289.5 305.7 308.3 312.5 5125.7 5157.1 5376.9 5412.8 5450.8 4625.7 4681.8 5038.5 5229.7 5232.5 3324.8 3354.9 3982.8 4041.7 4045.8 3701.5 3723.8 3873.7 4060.6 4063.9 3997.7 4023.5 4028.5 4039.3 4051.3 3538.7 3993.9 3998.7 4020.0 4059.9 2886.9 3473.9 3489.4 3518.7 3520.3 4151.8 5167.8 5203.7 5205.4 5255.7 4560.0 4567.5 4585.7 4595.0 4667.5 5623.8 5632.9 5647.6 5667.0 5692.5 3562.4 3777.1 3789.7 3826.2 3866.9 4570.6 4715.0 5757.2 5895.6 5940.2 3775.0 3820.3 3835.3 3876.5 3881.2 3486.9 3658.7 3897.7 3906.5 3936.3 4525.5 4526.5 4533.4 4563.5 4599.7 4718.9 5551.8 5580.7 5589.7 5605.5 1097.6 1248.7 1290.3 1298.8 1319.9 870.4 898.8 900.9 905.2 910.0 5209.5 5218.5 5225.2 5245.0 5267.9 4405.4 4814.9 5002.0 5464.7 5808.4 5120.7 5143.4 5166.2 5188.4 5246.6 2474.3 2496.5 2505.9 2514.0 2517.9 4535.4 4875.1 4910.4 5587.2 6044.5 5931.9 5944.3 5945.0 5963.5 5968.4 5312.7 5765.8 6000.2 6068.4 6082.7 5383.0 5506.9 5752.1 5773.9 5832.3 4833.3 5291.8 5706.5 5728.6 5764.7 4900.9 5807.5 5994.2 6014.9 6051.1 3816.2 3834.7 3857.6 3875.1 3910.8 4813.8 5336.3 5759.5 5806.7 5844.2 4326.6 5402.2 5635.9 5695.7 5698.8 4772.9 4895.6 5638.4 5643.9 5687.0 5233.1 5331.2 5406.7 5471.2 5498.0 5007.6 5463.9 5592.6 5704.3 5766.9 4347.5 4525.8 5323.9 5335.3 5580.6 12:36:06 INFO - PROCESS | 1926 | 12:36:06 INFO - PROCESS | 1926 | [#5] yui.html Cycles:180 Average:24264.71 Median:3848.35 stddev:38829.97 (1009.0%) stddev-sans-first:38898.58 12:36:06 INFO - PROCESS | 1926 | Values: 699.3 707.4 739.4 771.7 854.0 14895.9 15355.5 15709.7 15733.1 16181.5 14447.2 14453.7 14615.6 14622.9 14635.7 680.9 703.5 836.7 854.8 860.1 838.6 855.4 860.8 861.6 868.3 907.6 908.7 908.9 910.4 910.5 709.9 728.7 878.3 904.2 904.9 660.8 661.7 662.5 663.2 666.4 15263.5 15322.3 15347.2 15350.5 15351.2 13412.7 13457.1 13555.0 13673.9 14129.9 12163.5 12330.1 12418.1 12478.9 12948.6 3804.5 4471.2 4479.3 4520.8 4559.5 112768.4 112784.0 113427.0 116764.4 120680.4 10572.0 10619.4 10750.9 10850.1 11004.1 21252.0 21292.5 21302.8 21310.5 21315.3 7452.2 7561.5 7644.0 7702.2 7776.3 4133.3 4398.2 4412.4 4484.6 4590.4 3093.0 3309.7 3409.7 3522.7 3892.2 632.4 667.0 672.2 684.1 691.1 621.4 662.6 678.7 680.2 681.3 9119.8 9356.4 9610.7 9614.8 9650.2 514.6 587.1 673.5 679.8 680.4 4391.3 4412.8 4649.0 4822.2 4864.5 97231.0 97356.5 123028.5 143749.0 153626.0 134861.0 135138.0 138292.3 140273.6 143298.3 92295.5 95013.1 113944.4 114156.4 114610.0 7181.9 8049.3 8913.8 8963.3 8986.4 47583.2 63496.7 66060.6 66212.0 66922.7 90168.0 93757.5 94553.0 96715.0 97761.0 11531.7 11684.5 11756.1 11783.5 11988.3 12801.0 12985.0 13039.1 13154.1 13607.3 12043.8 12113.7 12171.0 12257.3 12461.8 11672.6 11889.8 12091.8 12209.8 12315.9 10139.7 10769.7 12067.9 12141.5 12263.1 10216.7 10316.0 10432.6 10580.8 10788.0 10278.5 10355.3 10464.4 10490.6 10545.6 12:36:06 INFO - PROCESS | 1926 | -------- Summary: end -------- 12:36:06 INFO - PROCESS | 1926 | 12:36:07 INFO - TEST-INFO | 1926: exit 0 12:36:07 INFO - TEST-OK | dromaeo_css | took 1117321ms 12:36:07 INFO - TEST-START | kraken 12:36:07 INFO - operating with platform_type : mac_ 12:36:07 INFO - Initialising browser for kraken test... 12:36:07 INFO - TEST-INFO | started process 1932 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpZMcVAD/profile http://localhost:49811/getInfo.html) 12:36:09 INFO - PROCESS | 1932 | __metrics Screen width/height:1600/1200 12:36:09 INFO - PROCESS | 1932 | colorDepth:24 12:36:09 INFO - PROCESS | 1932 | Browser inner width/height: 1024/689 12:36:09 INFO - PROCESS | 1932 | __metrics 12:36:09 INFO - PROCESS | 1932 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:36:09 INFO - PROCESS | 1932 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:36:10 INFO - TEST-INFO | 1932: exit 0 12:36:10 INFO - Browser initialized. 12:36:10 INFO - Running cycle 1/1 for kraken test... 12:36:10 INFO - TEST-INFO | started process 1933 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/T/tmpZMcVAD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:36:22 INFO - PROCESS | 1933 | RSS: Main: 236257280 12:36:22 INFO - PROCESS | 1933 | 12:36:24 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49811/tests/kraken/driver.html?testName=audio-beat-detection) 12:36:25 INFO - PROCESS | 1933 | RSS: Main: 294854656 12:36:25 INFO - PROCESS | 1933 | 12:36:28 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49811/tests/kraken/driver.html?testName=audio-dft) 12:36:29 INFO - PROCESS | 1933 | RSS: Main: 293781504 12:36:29 INFO - PROCESS | 1933 | 12:36:32 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49811/tests/kraken/driver.html?testName=audio-fft) 12:36:33 INFO - PROCESS | 1933 | RSS: Main: 384401408 12:36:33 INFO - PROCESS | 1933 | 12:36:35 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49811/tests/kraken/driver.html?testName=audio-oscillator) 12:36:35 INFO - PROCESS | 1933 | RSS: Main: 287166464 12:36:35 INFO - PROCESS | 1933 | 12:36:37 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49811/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:36:38 INFO - PROCESS | 1933 | RSS: Main: 291151872 12:36:38 INFO - PROCESS | 1933 | 12:36:41 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49811/tests/kraken/driver.html?testName=imaging-darkroom) 12:36:41 INFO - PROCESS | 1933 | RSS: Main: 328790016 12:36:41 INFO - PROCESS | 1933 | 12:36:44 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49811/tests/kraken/driver.html?testName=imaging-desaturate) 12:36:45 INFO - PROCESS | 1933 | RSS: Main: 329150464 12:36:45 INFO - PROCESS | 1933 | 12:36:48 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49811/tests/kraken/driver.html?testName=json-parse-financial) 12:36:48 INFO - PROCESS | 1933 | RSS: Main: 325357568 12:36:48 INFO - PROCESS | 1933 | 12:36:50 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49811/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:36:50 INFO - PROCESS | 1933 | RSS: Main: 300597248 12:36:50 INFO - PROCESS | 1933 | 12:36:52 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:36:53 INFO - PROCESS | 1933 | RSS: Main: 306454528 12:36:53 INFO - PROCESS | 1933 | 12:36:55 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:36:56 INFO - PROCESS | 1933 | RSS: Main: 308604928 12:36:56 INFO - PROCESS | 1933 | 12:36:59 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:36:59 INFO - PROCESS | 1933 | RSS: Main: 316456960 12:36:59 INFO - PROCESS | 1933 | 12:37:02 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:37:03 INFO - PROCESS | 1933 | RSS: Main: 318926848 12:37:03 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | Cycle 1(1): loaded http://localhost:49811/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49811/tests/kraken/driver.html?testName=ai-astar) 12:37:05 INFO - PROCESS | 1933 | RSS: Main: 362647552 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | __start_tp_report 12:37:05 INFO - PROCESS | 1933 | _x_x_mozilla_page_load 12:37:05 INFO - PROCESS | 1933 | _x_x_mozilla_page_load_details 12:37:05 INFO - PROCESS | 1933 | |i|pagename|runs| 12:37:05 INFO - PROCESS | 1933 | |0;ai-astar;113;113;113;112;112;113;113;113;112;114 12:37:05 INFO - PROCESS | 1933 | |1;audio-beat-detection;145;111;108;148;110;109;111;108;140;108 12:37:05 INFO - PROCESS | 1933 | |2;audio-dft;184;186;186;187;185;184;182;188;185;185 12:37:05 INFO - PROCESS | 1933 | |3;audio-fft;82;58;60;89;59;58;61;58;83;58 12:37:05 INFO - PROCESS | 1933 | |4;audio-oscillator;90;71;92;71;68;70;91;68;70;117 12:37:05 INFO - PROCESS | 1933 | |5;imaging-gaussian-blur;90;87;89;86;89;86;90;86;91;85 12:37:05 INFO - PROCESS | 1933 | |6;imaging-darkroom;97;102;96;97;98;98;98;97;98;98 12:37:05 INFO - PROCESS | 1933 | |7;imaging-desaturate;86;84;84;84;84;84;84;85;94;132 12:37:05 INFO - PROCESS | 1933 | |8;json-parse-financial;55;54;54;54;54;55;54;54;54;55 12:37:05 INFO - PROCESS | 1933 | |9;json-stringify-tinderbox;64;75;65;81;74;72;69;69;70;70 12:37:05 INFO - PROCESS | 1933 | |10;stanford-crypto-aes;86;88;95;85;85;85;85;85;87;89 12:37:05 INFO - PROCESS | 1933 | |11;stanford-crypto-ccm;140;137;141;147;131;145;148;154;172;140 12:37:05 INFO - PROCESS | 1933 | |12;stanford-crypto-pbkdf2;169;167;167;229;220;167;166;169;168;168 12:37:05 INFO - PROCESS | 1933 | |13;stanford-crypto-sha256-iterative;67;69;68;67;67;68;66;65;67;67 12:37:05 INFO - PROCESS | 1933 | __end_tp_report 12:37:05 INFO - PROCESS | 1933 | __start_cc_report 12:37:05 INFO - PROCESS | 1933 | _x_x_mozilla_cycle_collect,1938 12:37:05 INFO - PROCESS | 1933 | __end_cc_report 12:37:05 INFO - PROCESS | 1933 | __startTimestamp1475177825520__endTimestamp 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | ------- Summary: start ------- 12:37:05 INFO - PROCESS | 1933 | Number of tests: 14 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#0] ai-astar Cycles:10 Average:112.80 Median:113.00 stddev:0.63 (0.6%) stddev-sans-first:0.67 12:37:05 INFO - PROCESS | 1933 | Values: 113.0 113.0 113.0 112.0 112.0 113.0 113.0 113.0 112.0 114.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#1] audio-beat-detection Cycles:10 Average:119.80 Median:111.00 stddev:17.07 (15.4%) stddev-sans-first:15.48 12:37:05 INFO - PROCESS | 1933 | Values: 145.0 111.0 108.0 148.0 110.0 109.0 111.0 108.0 140.0 108.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#2] audio-dft Cycles:10 Average:185.20 Median:185.50 stddev:1.69 (0.9%) stddev-sans-first:1.73 12:37:05 INFO - PROCESS | 1933 | Values: 184.0 186.0 186.0 187.0 185.0 184.0 182.0 188.0 185.0 185.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#3] audio-fft Cycles:10 Average:66.60 Median:60.50 stddev:12.63 (20.9%) stddev-sans-first:12.11 12:37:05 INFO - PROCESS | 1933 | Values: 82.0 58.0 60.0 89.0 59.0 58.0 61.0 58.0 83.0 58.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#4] audio-oscillator Cycles:10 Average:80.80 Median:71.00 stddev:16.25 (22.9%) stddev-sans-first:16.90 12:37:05 INFO - PROCESS | 1933 | Values: 90.0 71.0 92.0 71.0 68.0 70.0 91.0 68.0 70.0 117.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:89.00 stddev:2.13 (2.4%) stddev-sans-first:2.12 12:37:05 INFO - PROCESS | 1933 | Values: 90.0 87.0 89.0 86.0 89.0 86.0 90.0 86.0 91.0 85.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#6] imaging-darkroom Cycles:10 Average:97.90 Median:98.00 stddev:1.60 (1.6%) stddev-sans-first:1.66 12:37:05 INFO - PROCESS | 1933 | Values: 97.0 102.0 96.0 97.0 98.0 98.0 98.0 97.0 98.0 98.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#7] imaging-desaturate Cycles:10 Average:90.10 Median:84.00 stddev:15.04 (17.9%) stddev-sans-first:15.88 12:37:05 INFO - PROCESS | 1933 | Values: 86.0 84.0 84.0 84.0 84.0 84.0 84.0 85.0 94.0 132.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#8] json-parse-financial Cycles:10 Average:54.30 Median:54.00 stddev:0.48 (0.9%) stddev-sans-first:0.44 12:37:05 INFO - PROCESS | 1933 | Values: 55.0 54.0 54.0 54.0 54.0 55.0 54.0 54.0 54.0 55.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:71.00 stddev:4.95 (7.0%) stddev-sans-first:4.58 12:37:05 INFO - PROCESS | 1933 | Values: 64.0 75.0 65.0 81.0 74.0 72.0 69.0 69.0 70.0 70.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#10] stanford-crypto-aes Cycles:10 Average:87.00 Median:86.50 stddev:3.16 (3.7%) stddev-sans-first:3.33 12:37:05 INFO - PROCESS | 1933 | Values: 86.0 88.0 95.0 85.0 85.0 85.0 85.0 85.0 87.0 89.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#11] stanford-crypto-ccm Cycles:10 Average:145.50 Median:146.00 stddev:11.29 (7.7%) stddev-sans-first:11.79 12:37:05 INFO - PROCESS | 1933 | Values: 140.0 137.0 141.0 147.0 131.0 145.0 148.0 154.0 172.0 140.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.00 Median:168.50 stddev:24.09 (14.3%) stddev-sans-first:25.28 12:37:05 INFO - PROCESS | 1933 | Values: 169.0 167.0 167.0 229.0 220.0 167.0 166.0 169.0 168.0 168.0 12:37:05 INFO - PROCESS | 1933 | 12:37:05 INFO - PROCESS | 1933 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.10 Median:67.00 stddev:1.10 (1.6%) stddev-sans-first:1.17 12:37:05 INFO - PROCESS | 1933 | Values: 67.0 69.0 68.0 67.0 67.0 68.0 66.0 65.0 67.0 67.0 12:37:05 INFO - PROCESS | 1933 | -------- Summary: end -------- 12:37:05 INFO - PROCESS | 1933 | 12:37:06 INFO - TEST-INFO | 1933: exit 0 12:37:06 INFO - TEST-OK | kraken | took 59220ms 12:37:06 INFO - SUITE-END | took 1176s 12:37:06 INFO - Completed test suite (00:19:36) 12:37:06 INFO - javascript benchmark 12:37:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2769.5568709006557, 3341.1480072581494, 3351.966480335196, 3381.05330507458, 3411.4200585900394, 5588.972055139719, 5653.1520271959225, 5743.081107022877, 5744.368119506851, 5749.79875704351, 5677.943220567793, 5681.460261275182, 5757.68332741699, 5783.306003279602, 5812.070068788984, 5021.824236151735, 5133.204353325241, 5136.563392111675, 5169.431362550135, 5214.973925130369, 4456.509783923766, 5236.973815130938, 5281.973590132044, 5288.814891478799, 5308.6814791112465, 5237.397699264595, 5256.710880901549, 5298.4966428189455, 5300.893982120375, 5317.867053323659, 4237.4491316129015, 5166.0, 5168.121419358718, 5176.430592634807, 5212.0, 5077.213031980049, 5104.438511763703, 5132.153194722866, 5148.665336753136, 5205.375354957391, 5674.148877668383, 5682.886342273173, 5697.259356283656, 5699.287589051369, 5781.942180578225, 6112.449879510821, 6126.36500017443, 6129.453906865831, 6183.280465569331, 6232.626042437469, 6174.197354343952, 6234.844128896814, 6294.118823364733, 6305.148804911324, 6316.557840951135, 5773.1051686988585, 5775.27809023872, 5779.306483222041, 5790.942090579125, 5832.0, 6165.660888651078, 6258.217722784652, 6297.811065668037, 6304.211973503312, 6313.084602732578, 6132.141360525245, 6135.32511423743, 6135.747419720469, 6137.662428566382, 6142.262928448526, 6263.749450022039, 6266.091416744546, 6270.655113968684, 6271.592346497463, 6277.591956522811, 6032.065029920369, 6122.816315510542, 6201.844953876189, 6206.472449841724, 6215.223097112861, 6113.658745237643, 6169.784057558054, 6179.351168127372, 6214.627122372672, 6271.247450305992, 4871.80512779492, 4875.585575226146, 4894.730789806524, 4911.729854857946, 4929.408470983433, 4235.25882970479, 4423.491298500714, 4428.911421771547, 4444.688871779009, 4466.330050492452, 5655.151727240865, 5674.375818659944, 5741.110127930177, 5750.626209296466, 5757.942420575824, 6267.62394256346, 6281.59169653971, 6294.402031806971, 6300.873982520324, 6307.810765677037, 5793.478586927196, 5834.970825145932, 5838.9124163137585, 5839.182514447895, 5839.474447299764, 5279.340082489689, 5300.496452836975, 5339.893202136013, 5371.086915224421, 5379.919301210485, 6219.129321894939, 6229.470495007975, 6245.718942647592, 6316.242050953915, 6320.5891617044745, 6271.62370257786, 6277.372262773869, 6281.748730050746, 6295.244570651551, 6308.968455157695, 6236.688165591793, 6247.937520624736, 6281.057841323838, 6283.3402492737605, 6284.842878928063, 6094.2077529920825, 6103.08453731926, 6110.923300256584, 6155.599886007395, 6200.534959878118, 6267.96866015667, 6275.435210831047, 6280.1207830902795, 6285.685715714288, 6297.2128483939505, 6072.574919755752, 6099.207103076572, 6113.694315284129, 6117.235345581802, 6140.3552626975315, 5137.51193636604, 5935.881282374242, 6010.489108425658, 6039.939600604113, 6067.757289708362, 5870.589058765845, 5905.320888097752, 5934.673592952429, 5947.791827286197, 5960.284765827955, 6100.328963813889, 6105.786297479567, 6106.267247930099, 6108.08378743192, 6128.662923539248, 5769.624974376652, 5814.331351894417, 5852.94147058524, 5885.911711324248, 5931.525477961839], "value": 5676.34481915094, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [476.3949783774605, 486.4114421549924, 509.14463700982344, 512.3314075131954, 555.0730280431678, 30736.0, 40286.798566007164, 41344.37983430251, 41369.7931510342, 41437.72092534913, 41898.79050604743, 42105.99999999992, 42274.15451690972, 42346.729598112026, 42391.57608423915, 8634.611442485104, 9587.0, 9797.167240784542, 9801.313908026441, 9804.215662747, 8693.869591956125, 11726.941365293162, 12027.45876435558, 12048.879511204863, 12102.0, 11938.22401543897, 11981.820272695917, 12003.459844307026, 12013.657336819324, 12041.217320874159, 11865.940670296679, 11971.880281197164, 11974.22167559106, 11975.940120299343, 12016.93991530041, 11610.6516804496, 11704.590339338167, 11711.063114950783, 11712.355820429873, 11717.765644687059, 39184.0, 39506.60493395058, 39550.0, 39622.80188599038, 39627.20745585101, 3185.824779637119, 3195.2491164576304, 3561.4123669594487, 3876.2441323941844, 3907.8436862525464, 24910.626340604904, 29856.85071574628, 31115.84442077798, 31463.52804707931, 31699.52450713241, 2757.7518023377993, 3189.8086114833186, 3249.301400198945, 3273.623533293678, 3294.8352582371026, 458762.0, 474782.0, 481090.0, 482298.0, 488832.5558372221, 57022.42977570254, 57228.71385643045, 57300.42699573034, 57353.0, 57391.0, 34282.314353713286, 34890.0, 34910.82544587261, 58572.41427585755, 58866.82266354649, 15814.520805422788, 15966.920165399331, 15975.680486390207, 15983.44057957966, 15994.600134996486, 11047.72380690489, 11293.435328233556, 11297.943510282561, 12224.633261002184, 12333.013358931265, 16099.919500402657, 16205.0, 16243.0, 18350.0, 18812.341568045053, 7219.592179525047, 7371.9262807372315, 7429.294217049372, 7494.587797671072, 7555.0, 816.8243827577099, 827.4084029918637, 905.4310973884426, 1021.7496713305288, 1066.6053560182781, 28470.0, 28836.42327153445, 29431.34480078138, 29498.410031799674, 29677.961271355398, 971.9745668319849, 1033.7105610429091, 1039.7608550033383, 1120.2942146447685, 1176.5117476247262, 14538.200398977946, 14977.625559361104, 15070.547883563511, 15292.388304467697, 15295.923520382326, 660034.0, 665098.0, 666359.0, 666430.0, 666844.0, 56102.719486402304, 56320.87358252812, 56562.0, 56576.43423565794, 56749.71625141848, 1035.3891204189565, 1206.51739304279, 1242.714175739585, 1339.7990301454665, 1359.7824348104455, 29012.83948642115, 30380.544291836082, 30664.540031899985, 30791.384172316877, 31308.68691313149, 47113.764431177624, 47653.76173119112, 47717.761411192725, 47839.521604783506, 47979.0, 565248.0, 569121.0, 570660.0, 571955.0, 573027.0, 26386.86806565955, 26890.0, 27134.829213663255, 27437.725622743517, 27742.583861241696, 45879.0, 46294.53705462902, 46600.7669961648, 46741.29888051614, 46992.530074698814, 37763.81118094392, 38054.429183562825, 38260.617393825705, 38292.61707382891, 38820.0, 37667.81166094152, 37836.62163378331, 37857.0, 38795.61204388033, 39082.60917390903, 37126.814365927996, 38051.61948380592, 38427.42358864563, 38464.230715384976, 38512.6148738509, 28383.14850554487, 30904.227394315323, 32234.71061157621, 34302.97091087271, 34331.0, 19523.926184059444, 24186.0, 24993.375165621004, 25007.874960625082, 25523.87238063798], "value": 19837.53698476837, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.4531058892831, 368.59270506090786, 370.8090333478259, 378.45124569374406, 398.1817365314278, 15069.095854248753, 15211.695766084686, 16207.0, 20728.859912704796, 20812.68780968283, 19210.711839322423, 19890.20439182431, 20276.391708248775, 20454.590908181828, 21051.05270262838, 5005.799768009293, 5932.3474417814, 6075.0, 6187.845303867394, 6218.8445288867915, 6026.8794624107495, 6078.118672792442, 6138.311964209834, 6160.1683772690785, 6161.29145148307, 5760.798372056979, 6005.039193729004, 6073.7874174403905, 6081.270247570275, 6097.0, 6028.156058151862, 6071.939280607182, 6072.939270607281, 6084.665343406111, 6086.45221930024, 3703.129764505339, 5209.1404918188455, 5254.949010197975, 5255.395629502618, 5270.736463176826, 25843.61234581483, 25925.83333749986, 26007.479850402888, 26230.34424139412, 26562.937482500678, 2150.827933765295, 2152.45112496314, 2160.8595441296425, 2173.7065496157975, 2205.878676672782, 14679.853201467955, 15736.449224277096, 15894.841051589452, 15960.920195399065, 16237.431689890915, 1812.5559237986645, 1898.155320882208, 1903.8857668539931, 1909.6467153576632, 1945.7956914523913, 112167.87832121656, 120534.0, 122415.0, 124022.0, 124244.0, 8929.24101451371, 10169.898301017043, 10408.687739367831, 10554.525046372779, 10892.183086268562, 13432.865671343357, 13463.192208467523, 13467.865321346857, 13477.932610336886, 13495.0, 8078.0, 8367.205115514016, 8379.45533540318, 8395.580220988926, 8533.871991920127, 8351.123132071167, 8354.665813367397, 8545.359098067669, 8546.31629469641, 8553.615087321086, 6415.582987105823, 6544.1349215473365, 6559.573627714183, 6569.967150164314, 6578.835529111715, 1443.978340324896, 1580.8498192671677, 1736.5658585353663, 1744.63918143848, 1822.82683145101, 880.9867851982225, 912.6760000199833, 930.330162283155, 940.0129863643148, 965.9806803864024, 20913.26803561868, 22212.444688882908, 22292.554148917254, 22389.776102239095, 22417.551648966928, 754.9509281896659, 889.3685483306924, 909.9636014559476, 948.4025064209504, 1040.1782591752583, 10332.948335258427, 11492.712682183012, 11614.6515604532, 11775.293482391084, 11837.763244735057, 504493.0, 506780.0, 511871.0, 576523.0, 602722.9863850741, 92882.0, 117106.0, 123703.381483092, 133379.0, 139530.0, 891.2914233184633, 938.2119020023214, 974.7952929884661, 979.2410881566842, 1005.0100650859044, 13415.798763018367, 14328.641783955067, 17343.78593498443, 18931.148098335332, 18986.335478258196, 51716.965660687325, 52944.235018848485, 54033.459665404414, 54464.91070178495, 55340.44659553353, 58931.410685892595, 76820.2317976813, 78028.0, 79776.20783272557, 80270.0, 7632.694692212472, 7800.804979875548, 7857.135715071223, 7925.881111783443, 7938.761837144895, 10190.490475476048, 12297.815532766837, 12418.068644851854, 12574.811377829523, 12610.49558017669, 10505.212109092, 10783.299085559414, 10793.460326983777, 10900.127989760958, 10909.563617455531, 10455.634052808115, 10539.262251642618, 10657.41384223844, 10757.9462102689, 10782.299150555189, 11026.944865275622, 11124.833127503256, 11165.497552610315, 11202.49588768491, 11272.380019099028, 7754.883676744967, 7804.6878124874365, 7823.452358335089, 7867.17394673551, 7898.2101789822855, 9855.605775769176, 10466.685999419726, 10669.41318227505, 10983.61557345489, 11102.888971110186], "value": 9510.777367337465, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.830396200475, 1481.3704175725318, 1617.5228307649243, 1782.84845788108, 1835.3576248313084, 8347.20701533354, 8829.955850220747, 8846.911530884689, 8851.91148088519, 8867.423617464869, 7720.536767793937, 8042.276195142436, 8049.959750201256, 8408.075111737704, 8653.221210091091, 2338.5206032763285, 2367.8934447949796, 2368.2421625079983, 2373.762623737621, 2376.0970831084164, 2339.695839540866, 2345.483993521431, 2375.9168429104984, 2376.988115059431, 2378.0606660369144, 2285.714285714286, 2294.908203671851, 2309.5265470578493, 2370.0, 2375.9406014849596, 2316.525112351964, 2340.6840076589615, 2341.438054866837, 2346.3195673254736, 2349.5066036132434, 2326.685897403846, 2336.7896889280046, 2341.6370462578157, 2342.0397636968933, 2347.730011048717, 7547.207543207994, 8647.264982476498, 8727.650893964234, 8777.253933415668, 8815.559222038937, 5149.433562308143, 5247.921281180747, 5340.439253878326, 5354.5180933715765, 5358.4641535846495, 3368.6799754023327, 3501.24723184514, 3573.428251479777, 3575.0526110580718, 3584.4623306503972, 1005.2109094360892, 1039.7244730146517, 1040.2614143957799, 1059.0, 1061.7345663584103, 11351.20541562091, 11538.711532211679, 11550.88449115515, 11552.191346605741, 11565.363904985223, 4502.752348620792, 4610.884727881829, 4761.9047619048115, 4790.401199850019, 4793.195980351534, 9805.313628046111, 9810.0, 9817.01829817027, 9826.557804898654, 9834.311598188202, 5288.312519372457, 5294.97352513235, 5296.576273898079, 5300.416954135042, 5309.33633295838, 4357.760323182192, 4392.560743925582, 4535.9773201133785, 4712.59942904857, 4866.245731911559, 3835.9424608630893, 3843.5387753469763, 3959.5050618672667, 3968.146848427602, 4003.7597744135446, 1263.0716423428773, 1281.9615411537668, 1285.736424033071, 1296.1639742366121, 1297.5199176304827, 808.5754978636145, 884.0319849764497, 900.3967341880956, 905.4702998745676, 936.4708939449189, 5712.742926568314, 5754.395788442237, 5926.288845338587, 6116.969415152985, 6245.437910587978, 887.2768693514765, 888.9466632002101, 907.2651152566442, 910.7723069232692, 917.3349321741684, 3882.941755873664, 3912.9021774455296, 3915.0, 4018.618231268025, 4046.109855831712, 10235.232357573062, 10723.89276107229, 10731.692686072533, 10738.248322617343, 10739.355638661706, 10643.308184967953, 10669.893301067044, 10683.198760093024, 10683.358998460117, 10709.410982396043, 7578.583177925267, 7900.841983160304, 7939.20607939213, 7946.404019698662, 7984.520928744178, 5513.255710479034, 5820.912686309624, 5856.765729370777, 6004.339522652417, 6067.393260673897, 9240.722778316662, 9481.383710058824, 9539.570719317786, 9586.568604412678, 9609.9039009609, 8440.66237350499, 9325.487098209393, 9739.172170365599, 10181.083702466814, 10191.0, 6314.652694101868, 7429.888551671621, 7537.54774713519, 7579.5073320234005, 7636.236376362319, 8216.712415065444, 8264.958675206586, 8279.83440331178, 8294.502329860228, 8307.003159620885, 7274.381677557255, 7391.778246652609, 7431.702731890664, 7489.962550187214, 7499.0, 6181.9690901547, 7238.131424229126, 7259.0, 7278.0, 7330.890036649348, 6870.690818913261, 6915.446764258948, 6999.825004374931, 7002.964985175042, 7011.228764835966, 4935.925961110658, 4985.601151907911, 5017.4731653175895, 5046.09170349326, 5119.897602048012, 4942.382202224722, 4977.303177555217, 5006.474320196473, 5172.948270517247, 5212.009718153538], "value": 4537.094480212325, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.7263019090337, 289.53818659238505, 305.7401208972374, 308.2601755786113, 312.507800214662, 5125.692458452486, 5157.071727089123, 5376.919346209805, 5412.810551630694, 5450.836474905749, 4625.722456652603, 4681.83613573525, 5038.548594212966, 5229.712365819878, 5232.534890230732, 3324.75064370172, 3354.899353019401, 3982.820773065219, 4041.6968727345493, 4045.777482238476, 3701.482392219191, 3723.8324275407535, 3873.7288389812725, 4060.634542891139, 4063.8577649782264, 3997.72015958883, 4023.517177938637, 4028.47629808126, 4039.313316736147, 4051.311277082903, 3538.6992105671047, 3993.8602148924792, 3998.7403967750065, 4019.974906398879, 4059.939100913489, 2886.942261154765, 3473.913152171216, 3489.36000079962, 3518.665726755954, 3520.2542017226606, 4151.83392664293, 5167.767450464738, 5203.687778733288, 5205.401378841444, 5255.684658920439, 4560.042391097873, 4567.510706664875, 4585.747783871886, 4595.012072404415, 4667.4632417272105, 5623.831285061455, 5632.944280118309, 5647.632903861236, 5667.0, 5692.544596432275, 3562.4300111982216, 3777.0557360659836, 3789.745101664505, 3826.1582451860822, 3866.903327416809, 4570.611498022672, 4714.95285047152, 5757.165211044375, 5895.55334536167, 5940.227770389865, 3774.999625099351, 3820.3123437781464, 3835.2521258354354, 3876.5154355705536, 3881.165549406891, 3486.877959271413, 3658.652428019334, 3897.707671924617, 3906.511686039245, 3936.3111455495173, 4525.52481989393, 4526.456825181, 4533.365328853963, 4563.54364563541, 4599.7470139142, 4718.8584342469785, 5551.750171242223, 5580.665160090408, 5589.74846131925, 5605.495505404533, 1097.5664612478026, 1248.744007478483, 1290.3419256179416, 1298.8441387033618, 1319.8878095362002, 870.4211699220075, 898.8180542586477, 900.8783814185001, 905.212465155306, 909.9535534135797, 5209.479052094836, 5218.452062533455, 5225.16397376418, 5244.9737751310995, 5267.920076384332, 4405.414050941658, 4814.855554333376, 5001.974990125026, 5464.699441530674, 5808.361080281165, 5120.667156634881, 5143.3570803649545, 5166.225066240019, 5188.351456067991, 5246.606504512177, 2474.3421907322136, 2496.5270490410744, 2505.924822255335, 2513.951129829183, 2517.949641007206, 4535.367267783596, 4875.123677030569, 4910.361652985089, 5587.217789509472, 6044.516438684895, 5931.851703707442, 5944.316403613553, 5945.0, 5963.4931030862, 5968.432998865101, 5312.707801070901, 5765.798197063083, 6000.249968753906, 6068.423499767515, 6082.665453400017, 5382.973085134549, 5506.944930550643, 5752.0509115997065, 5773.942260577341, 5832.329282132439, 4833.275008748716, 5291.76187071575, 5706.544831068051, 5728.6201141325155, 5764.654120752768, 4900.854103191706, 5807.535397168301, 5994.220751302303, 6014.917314883188, 6051.062085376773, 3816.1985982943893, 3834.712396570324, 3857.594952529944, 3875.0818344919444, 3910.7904034220574, 4813.831515896927, 5336.252924590638, 5759.523404935918, 5806.709664516673, 5844.240248767803, 4326.637109310557, 5402.216678581505, 5635.8591035224445, 5695.715214239354, 5698.800541981177, 4772.880677983078, 4895.583875370491, 5638.407967163389, 5643.887122257614, 5686.9715651421475, 5233.1013395020955, 5331.173668081454, 5406.702151585482, 5471.23402723627, 5497.972510137423, 5007.624428167975, 5463.869091054413, 5592.608517403905, 5704.258446401941, 5766.884662306814, 4347.500037495728, 4525.773711314355, 5323.893522129459, 5335.33308336458, 5580.553555715452], "value": 4034.04103836415, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.2552931128345, 707.4057791455177, 739.3641468337225, 771.6681826814471, 853.9573021348933, 14895.851041489583, 15355.462558810445, 15709.700962797811, 15733.05601663901, 16181.514554563353, 14447.205403702792, 14453.710925781477, 14615.561533153996, 14622.853771462282, 14635.707285854276, 680.9250982391933, 703.4829400390735, 836.6527890925258, 854.8033952190968, 860.1183786618703, 838.6435764799966, 855.4054931822386, 860.7848037990502, 861.5735211070529, 868.288003836854, 907.6233363154283, 908.6728777640077, 908.8500397434449, 910.4127837544779, 910.4628269321132, 709.9467539934526, 728.719443014438, 878.2885862451371, 904.2043002158092, 904.9321300902459, 660.8480049588574, 661.6856992928368, 662.526293700002, 663.2207156591035, 666.3702800853196, 15263.465778697804, 15322.310496027705, 15347.232638368148, 15350.539483815392, 15351.155686437241, 13412.664683382896, 13457.058005939589, 13555.0, 13673.931630341884, 14129.929350353284, 12163.5134594617, 12330.116013455732, 12418.068644851672, 12478.875211247861, 12948.611541653765, 3804.5434547854434, 4471.150481408555, 4479.2609219478745, 4520.841770538015, 4559.544045595414, 112768.43615781868, 112784.0, 113427.0, 116764.41617791941, 120680.39659801645, 10572.0, 10619.42490164887, 10750.946245268724, 10850.0777433919, 11004.119670426348, 21252.0, 21292.467688307606, 21302.78697213008, 21310.467238319165, 21315.29477641785, 7452.217517160729, 7561.546307221584, 7643.9617801911745, 7702.152763196044, 7776.339011184113, 4133.297339452301, 4398.208322501916, 4412.411531848532, 4484.618807401407, 4590.449146102423, 3093.0256969054676, 3309.7186739126964, 3409.7272218222483, 3522.682958533693, 3892.163184915257, 632.4434497642137, 666.9566478178903, 672.1530871102447, 684.0525871667776, 691.1084700736095, 621.3724138620026, 662.6057495789997, 678.718331892269, 680.2313385873931, 681.3288910423229, 9119.81760364789, 9356.4386136832, 9610.711678649652, 9614.807703846023, 9650.227981761442, 514.5960421069442, 587.0636335045681, 673.5083389125965, 679.78926532775, 680.363859791097, 4391.341298805205, 4412.823487060546, 4648.97675511627, 4822.180229361017, 4864.489228631013, 97231.0, 97356.53965190527, 123028.53942921091, 143749.0, 153626.0, 134861.0, 135138.0, 138292.30853845665, 140273.5972640281, 143298.28350858387, 92295.53852230696, 95013.14960551195, 113944.43027784974, 114156.42921785338, 114610.0, 7181.9227115930935, 8049.315808156372, 8913.777155571162, 8963.327750418875, 8986.415882967585, 47583.15254881232, 63496.68251658712, 66060.56668182688, 66212.0, 66922.66154676977, 90168.0, 93757.5312123435, 94553.0, 96715.0, 97761.02238977804, 11531.654050378502, 11684.532618695497, 11756.059515238589, 11783.469743861375, 11988.280703157838, 12801.0, 12985.0, 13039.148317449253, 13154.144980576231, 13607.251601162032, 12043.75912481728, 12113.69715757113, 12171.02631789437, 12257.32584707814, 12461.813072803734, 11672.649820505398, 11889.82165267539, 12091.818622720843, 12209.755804884031, 12315.93842030784, 10139.695809125738, 10769.676909692722, 12067.939660301643, 12141.453634586642, 12263.141580089308, 10216.693499195037, 10316.0, 10432.58269669235, 10580.788384232119, 10787.975142361463, 10278.537465813906, 10355.275130741076, 10464.372137671764, 10490.580376784843, 10545.57817687284], "value": 7641.8479703976245, "unit": "score"}], "name": "dromaeo_css", "value": 7287.679998842196, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 113.0, 112.0, 112.0, 113.0, 113.0, 113.0, 112.0, 114.0], "value": 112.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 111.0, 108.0, 148.0, 110.0, 109.0, 111.0, 108.0, 140.0, 108.0], "value": 119.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 186.0, 186.0, 187.0, 185.0, 184.0, 182.0, 188.0, 185.0, 185.0], "value": 185.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 89.0, 59.0, 58.0, 61.0, 58.0, 83.0, 58.0], "value": 66.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 71.0, 92.0, 71.0, 68.0, 70.0, 91.0, 68.0, 70.0, 117.0], "value": 80.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 89.0, 86.0, 89.0, 86.0, 90.0, 86.0, 91.0, 85.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 102.0, 96.0, 97.0, 98.0, 98.0, 98.0, 97.0, 98.0, 98.0], "value": 97.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 84.0, 84.0, 84.0, 84.0, 85.0, 94.0, 132.0], "value": 90.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 55.0], "value": 54.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 75.0, 65.0, 81.0, 74.0, 72.0, 69.0, 69.0, 70.0, 70.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 95.0, 85.0, 85.0, 85.0, 85.0, 85.0, 87.0, 89.0], "value": 87.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 137.0, 141.0, 147.0, 131.0, 145.0, 148.0, 154.0, 172.0, 140.0], "value": 145.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 167.0, 229.0, 220.0, 167.0, 166.0, 169.0, 168.0, 168.0], "value": 179.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 69.0, 68.0, 67.0, 67.0, 68.0, 66.0, 65.0, 67.0, 67.0], "value": 67.1, "unit": "score"}], "name": "kraken", "value": 1444.8999999999996, "alertThreshold": 2.0}]} 12:37:06 INFO - Return code: 0 12:37:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:37:06 INFO - # TBPL SUCCESS # 12:37:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:37:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:37:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:37:06 INFO - 2016-09-29 12:37:06.861 screenresolution[1937:11298] starting screenresolution argv=screenresolution get 12:37:06 INFO - 2016-09-29 12:37:06.863 screenresolution[1937:11298] Display 0: 1600x1200x32@60 12:37:06 INFO - 2016-09-29 12:37:06.869 screenresolution[1938:11301] starting screenresolution argv=screenresolution list 12:37:06 INFO - Available Modes on Display 0 12:37:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:37:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:37:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:37:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:37:07 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:37:07 INFO - Intel Iris: 12:37:07 INFO - Chipset Model: Intel Iris 12:37:07 INFO - Type: GPU 12:37:07 INFO - Bus: Built-In 12:37:07 INFO - VRAM (Dynamic, Max): 1536 MB 12:37:07 INFO - Vendor: Intel (0x8086) 12:37:07 INFO - Device ID: 0x0a2e 12:37:07 INFO - Revision ID: 0x0009 12:37:07 INFO - Displays: 12:37:07 INFO - Display: 12:37:07 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:37:07 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:37:07 INFO - Display Serial Number: 1600x1200 60 12:37:07 INFO - Main Display: Yes 12:37:07 INFO - Mirror: Off 12:37:07 INFO - Online: Yes 12:37:07 INFO - Rotation: Supported 12:37:07 INFO - Return code: 0 12:37:07 INFO - Running post-action listener: _resource_record_post_action 12:37:07 INFO - [mozharness: 2016-09-29 19:37:07.104051Z] Finished run-tests step (success) 12:37:07 INFO - Running post-run listener: _resource_record_post_run 12:37:07 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 1048576; Write bytes: 705861632; Read time: 47; Write time: 2180 12:37:07 INFO - TinderboxPrint: CPU usage
53.6% 12:37:07 INFO - TinderboxPrint: I/O read bytes / time
1,048,576 / 47 12:37:07 INFO - TinderboxPrint: I/O write bytes / time
705,861,632 / 2,180 12:37:07 INFO - TinderboxPrint: CPU idle
2,251.1 (46.4%) 12:37:07 INFO - TinderboxPrint: CPU system
968.8 (20.0%) 12:37:07 INFO - TinderboxPrint: CPU user
1,629.8 (33.6%) 12:37:07 INFO - TinderboxPrint: Swap in / out
407,965,696 / 0 12:37:07 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 408066560; Write bytes: 409372160; Read time: 30693; Write time: 759 12:37:07 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 983040; Write bytes: 295002624; Read time: 46; Write time: 1414 12:37:07 INFO - Running post-run listener: _upload_blobber_files 12:37:07 INFO - Blob upload gear active. 12:37:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:37:07 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 12:37:07 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:37:07 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:37:08 INFO - (blobuploader) - INFO - Open directory for files ... 12:37:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:37:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:37:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:08 INFO - (blobuploader) - INFO - Done attempting. 12:37:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:37:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:37:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:09 INFO - (blobuploader) - INFO - Done attempting. 12:37:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:37:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:37:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:37:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:37:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:37:12 INFO - (blobuploader) - INFO - Done attempting. 12:37:12 INFO - (blobuploader) - INFO - Iteration through files over. 12:37:12 INFO - Return code: 0 12:37:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:37:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:37:12 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4af5de00d3e965737c48e31e298d7d034a65a1eca57058d705e60ae88434fb563a3cf43b2f995bb1674c7d3d5709606a602050637f2d31c1b1697bbacca53b54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/567960bc5b80e9a1dd0447f2aaaa34571af35877f726a8d20b5bc3f38dc62f4785e1c52c2042b194d304f3cd2bea70ba6db2409b588feb03fceb47da457c9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ed04fa4883f5bc98608fed1fd53b961f5ce20f0a7e2d03b878b3b42b08d0df24478cd8673337e6682e11f7cc4ca3ca63821d0298348c1385957a851a6d5602e"} 12:37:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:37:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:37:12 INFO - Contents: 12:37:12 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4af5de00d3e965737c48e31e298d7d034a65a1eca57058d705e60ae88434fb563a3cf43b2f995bb1674c7d3d5709606a602050637f2d31c1b1697bbacca53b54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/567960bc5b80e9a1dd0447f2aaaa34571af35877f726a8d20b5bc3f38dc62f4785e1c52c2042b194d304f3cd2bea70ba6db2409b588feb03fceb47da457c9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ed04fa4883f5bc98608fed1fd53b961f5ce20f0a7e2d03b878b3b42b08d0df24478cd8673337e6682e11f7cc4ca3ca63821d0298348c1385957a851a6d5602e"} 12:37:12 INFO - Running post-run listener: copy_logs_to_upload_dir 12:37:12 INFO - Copying logs to upload dir... 12:37:12 INFO - mkdir: /builds/slave/test/build/upload/logs 12:37:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.399259 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-29 12:37:12.325424) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 12:37:12.333285) ========= 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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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/Fx-Team/sha512/4af5de00d3e965737c48e31e298d7d034a65a1eca57058d705e60ae88434fb563a3cf43b2f995bb1674c7d3d5709606a602050637f2d31c1b1697bbacca53b54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/567960bc5b80e9a1dd0447f2aaaa34571af35877f726a8d20b5bc3f38dc62f4785e1c52c2042b194d304f3cd2bea70ba6db2409b588feb03fceb47da457c9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ed04fa4883f5bc98608fed1fd53b961f5ce20f0a7e2d03b878b3b42b08d0df24478cd8673337e6682e11f7cc4ca3ca63821d0298348c1385957a851a6d5602e"} build_url:https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011143 build_url: 'https://queue.taskcluster.net/v1/task/NruArK2MRPOF1exM09qwqg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4af5de00d3e965737c48e31e298d7d034a65a1eca57058d705e60ae88434fb563a3cf43b2f995bb1674c7d3d5709606a602050637f2d31c1b1697bbacca53b54", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/567960bc5b80e9a1dd0447f2aaaa34571af35877f726a8d20b5bc3f38dc62f4785e1c52c2042b194d304f3cd2bea70ba6db2409b588feb03fceb47da457c9b37", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/4ed04fa4883f5bc98608fed1fd53b961f5ce20f0a7e2d03b878b3b42b08d0df24478cd8673337e6682e11f7cc4ca3ca63821d0298348c1385957a851a6d5602e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-29 12:37:12.368769) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 12:37:12.369128) ========= 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.9BXDKLd5A0/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.WQZfJDFQPf/Listeners TMPDIR=/var/folders/qx/_j4hsj951qgdfkk93ckl0nxc00000w/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.005189 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-29 12:37:12.417460) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 12:37:12.417872) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-29 12:37:12.418204) ========= ========= Total master_lag: 0.20 =========