builder: cedar_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0104 starttime: 1478531424.16 results: success (0) buildid: 20161107041537 builduid: a04dca672c164725a9b45b381a1f895c revision: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.163091) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.163628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.163911) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.617479) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.617802) ========= 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.vgedz3FKd3/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.2DOdEIFZrR/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-11-07 07:10:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 72.1M=0s 2016-11-07 07:10:25 (72.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.144979 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.882615) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.882903) ========= 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.vgedz3FKd3/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.2DOdEIFZrR/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.036163 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:24.966145) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-07 07:10:24.966491) ========= bash -c 'python archiver_client.py mozharness --repo projects/cedar --rev 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/cedar --rev 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgedz3FKd3/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.2DOdEIFZrR/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-11-07 07:10:25,328 truncating revision to first 12 chars 2016-11-07 07:10:25,328 Setting DEBUG logging. 2016-11-07 07:10:25,328 attempt 1/10 2016-11-07 07:10:25,328 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/cedar/36b139f0c130?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-11-07 07:10:26,875 unpacking tar archive at: cedar-36b139f0c130/testing/mozharness/ program finished with exit code 0 elapsedTime=1.780924 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-11-07 07:10:26.778764) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:26.782053) ========= script_repo_revision: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:26.782441) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:26.782705) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-11-07 07:10:26.798634) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-07 07:10:26.798905) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Cedar', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Cedar'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.vgedz3FKd3/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.2DOdEIFZrR/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:10:27 INFO - MultiFileLogger online at 20161107 07:10:27 in /builds/slave/test 07:10:27 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Cedar --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Cedar 07:10:27 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:10:27 INFO - {'append_to_log': False, 07:10:27 INFO - 'base_work_dir': '/builds/slave/test', 07:10:27 INFO - 'blob_upload_branch': 'Cedar', 07:10:27 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:10:27 INFO - 'branch': 'Cedar', 07:10:27 INFO - 'buildbot_json_path': 'buildprops.json', 07:10:27 INFO - 'config_files': ('talos/mac_config.py',), 07:10:27 INFO - 'default_actions': ('clobber', 07:10:27 INFO - 'read-buildbot-config', 07:10:27 INFO - 'download-and-extract', 07:10:27 INFO - 'populate-webroot', 07:10:27 INFO - 'create-virtualenv', 07:10:27 INFO - 'install', 07:10:27 INFO - 'run-tests'), 07:10:27 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:10:27 INFO - 'download_minidump_stackwalk': True, 07:10:27 INFO - 'download_symbols': 'ondemand', 07:10:27 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:10:27 INFO - 'tooltool.py': '/tools/tooltool.py', 07:10:27 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:10:27 INFO - '/tools/misc-python/virtualenv.py')}, 07:10:27 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:10:27 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:10:27 INFO - 'installer_path': 'installer.exe', 07:10:27 INFO - 'log_level': 'info', 07:10:27 INFO - 'log_name': 'talos', 07:10:27 INFO - 'log_to_console': True, 07:10:27 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:10:27 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:10:27 INFO - 'opt_config_files': (), 07:10:27 INFO - 'pip_index': False, 07:10:27 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:27 INFO - 'cmd': ('bash', 07:10:27 INFO - '-c', 07:10:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:27 INFO - 'enabled': True, 07:10:27 INFO - 'halt_on_failure': False, 07:10:27 INFO - 'name': 'check_screen_resolution'},), 07:10:27 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:10:27 INFO - 'cmd': ('bash', 07:10:27 INFO - '-c', 07:10:27 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:10:27 INFO - 'enabled': True, 07:10:27 INFO - 'halt_on_failure': False, 07:10:27 INFO - 'name': 'check_screen_resolution'},), 07:10:27 INFO - 'run_cmd_checks_enabled': True, 07:10:27 INFO - 'sps_profile': False, 07:10:27 INFO - 'sps_profile_interval': 0, 07:10:27 INFO - 'suite': 'dromaeojs', 07:10:27 INFO - 'system_bits': '32', 07:10:27 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:10:27 INFO - 'title': 't-yosemite-r7-0104', 07:10:27 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:10:27 INFO - 'use_talos_json': True, 07:10:27 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:10:27 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:10:27 INFO - 'work_dir': 'build'} 07:10:27 INFO - [mozharness: 2016-11-07 15:10:27.280059Z] Running clobber step. 07:10:27 INFO - Running pre-action listener: _resource_record_pre_action 07:10:27 INFO - Running main action method: clobber 07:10:27 INFO - rmtree: /builds/slave/test/build 07:10:27 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:10:28 INFO - Running post-action listener: _resource_record_post_action 07:10:28 INFO - [mozharness: 2016-11-07 15:10:28.830139Z] Finished clobber step (success) 07:10:28 INFO - [mozharness: 2016-11-07 15:10:28.830299Z] Running read-buildbot-config step. 07:10:28 INFO - Running pre-action listener: _resource_record_pre_action 07:10:28 INFO - Running main action method: read_buildbot_config 07:10:28 INFO - Using buildbot properties: 07:10:28 INFO - { 07:10:28 INFO - "project": "", 07:10:28 INFO - "product": "firefox", 07:10:28 INFO - "script_repo_revision": "default", 07:10:28 INFO - "builddir": "cedar_yosemite_r7_test-dromaeojs", 07:10:28 INFO - "repository": "", 07:10:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 cedar talos dromaeojs", 07:10:28 INFO - "buildid": "20161107041537", 07:10:28 INFO - "pgo_build": "False", 07:10:28 INFO - "basedir": "/builds/slave/test", 07:10:28 INFO - "buildnumber": 1, 07:10:28 INFO - "slavename": "t-yosemite-r7-0104", 07:10:28 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 07:10:28 INFO - "platform": "yosemite_r7", 07:10:28 INFO - "scheduler": "tests-cedar-macosx64-talos", 07:10:28 INFO - "branch": "cedar", 07:10:28 INFO - "repo_path": "projects/cedar", 07:10:28 INFO - "revision": "36b139f0c1300e703ab80a1ef58f56bbc55d87e4", 07:10:28 INFO - "stage_platform": "macosx64", 07:10:28 INFO - "builduid": "a04dca672c164725a9b45b381a1f895c", 07:10:28 INFO - "slavebuilddir": "test" 07:10:28 INFO - } 07:10:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 07:10:28 INFO - Running post-action listener: _resource_record_post_action 07:10:28 INFO - [mozharness: 2016-11-07 15:10:28.833686Z] Finished read-buildbot-config step (success) 07:10:28 INFO - [mozharness: 2016-11-07 15:10:28.833819Z] Running download-and-extract step. 07:10:28 INFO - Running pre-action listener: _resource_record_pre_action 07:10:28 INFO - Running main action method: download_and_extract 07:10:28 INFO - mkdir: /builds/slave/test/build/tests 07:10:28 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')]} 07:10:28 INFO - trying https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 07:10:28 INFO - Downloading https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/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 07:10:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/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 07:10:29 INFO - Downloaded 1199 bytes. 07:10:29 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 07:10:29 INFO - Using the following test package requirements: 07:10:29 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:10:29 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 07:10:29 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 07:10:29 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'jsshell-mac.zip'], 07:10:29 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 07:10:29 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 07:10:29 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 07:10:29 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 07:10:29 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 07:10:29 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 07:10:29 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 07:10:29 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:10:29 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:10:29 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:29 INFO - Fetch https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:10:30 INFO - Http code: 200 07:10:30 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 07:10:30 INFO - x-amz-cf-id: IQoggMdS8OsB18Vwrohdg1OFCKf1ICEPP-2K2OuqkZ--iJsrYAqBIQ== 07:10:30 INFO - x-amz-version-id: 8K4TreKjo0dV8yc6WlJaJU04rO1DioPM 07:10:30 INFO - Expected file size: 16308131 07:10:30 INFO - Obtained file size: 16308131 07:10:37 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 07:10:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 07:10:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 07:10:37 INFO - Fetch https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 07:10:38 INFO - Http code: 200 07:10:38 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 07:10:38 INFO - x-amz-cf-id: b5HxNbgLqolfofwptCIm7768av08zj0AzPp4uoqpQ0PwJ_XN9Z9bUA== 07:10:38 INFO - x-amz-version-id: 8K4TreKjo0dV8yc6WlJaJU04rO1DioPM 07:10:38 INFO - Expected file size: 16308131 07:10:38 INFO - Obtained file size: 16308131 07:10:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 07:10:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 07:10:44 INFO - Fetch https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 07:10:45 INFO - Http code: 200 07:10:45 INFO - via: 1.1 37af14ee951b12568cf1cc160d08abe6.cloudfront.net (CloudFront) 07:10:45 INFO - x-amz-cf-id: ryyXN6QVZhrdLhYAb47fiMAX046f5kL7ydDxJphJ88xo4g4lpZ7bCg== 07:10:45 INFO - x-amz-version-id: XfTi1OKEYzqpg35SjLIGRLAjTQqEXnil 07:10:45 INFO - Expected file size: 14409038 07:10:45 INFO - Obtained file size: 14409038 07:10:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:10:45 INFO - trying https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:10:45 INFO - Downloading https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 07:10:45 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/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 07:10:49 INFO - Downloaded 111588017 bytes. 07:10:49 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:10:49 INFO - mkdir: /builds/slave/test/properties 07:10:49 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:10:49 INFO - Writing to file /builds/slave/test/properties/build_url 07:10:49 INFO - Contents: 07:10:49 INFO - build_url:https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 07:10:50 INFO - Running post-action listener: _resource_record_post_action 07:10:50 INFO - Running post-action listener: set_extra_try_arguments 07:10:50 INFO - [mozharness: 2016-11-07 15:10:50.409113Z] Finished download-and-extract step (success) 07:10:50 INFO - [mozharness: 2016-11-07 15:10:50.409278Z] Running populate-webroot step. 07:10:50 INFO - Running pre-action listener: _resource_record_pre_action 07:10:50 INFO - Running main action method: populate_webroot 07:10:50 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:10:50 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:10:50 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:10:50 INFO - '--tppagecycles', 07:10:50 INFO - '7'], 07:10:50 INFO - 'tests': ['tsvgm']}}, 07:10:50 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:10:50 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:10:50 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:10:50 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:10:50 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:10:50 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:10:50 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['damp', 'tps']}, 07:10:50 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'tests': ['damp', 'tps']}, 07:10:50 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['dromaeo_dom']}, 07:10:50 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:10:50 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['basic_compositor_video']}, 07:10:50 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:10:50 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['a11yr', 07:10:50 INFO - 'ts_paint', 07:10:50 INFO - 'tpaint', 07:10:50 INFO - 'sessionrestore', 07:10:50 INFO - 'sessionrestore_no_auto_restore', 07:10:50 INFO - 'tabpaint']}, 07:10:50 INFO - 'other-e10s': {'tests': ['a11yr', 07:10:50 INFO - 'ts_paint', 07:10:50 INFO - 'tpaint', 07:10:50 INFO - 'sessionrestore', 07:10:50 INFO - 'sessionrestore_no_auto_restore', 07:10:50 INFO - 'tabpaint']}, 07:10:50 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:10:50 INFO - 'ts_paint', 07:10:50 INFO - 'tpaint', 07:10:50 INFO - 'sessionrestore', 07:10:50 INFO - 'sessionrestore_no_auto_restore', 07:10:50 INFO - 'tabpaint']}, 07:10:50 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:10:50 INFO - 'ts_paint', 07:10:50 INFO - 'tpaint', 07:10:50 INFO - 'sessionrestore', 07:10:50 INFO - 'sessionrestore_no_auto_restore', 07:10:50 INFO - 'tabpaint']}, 07:10:50 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['a11yr', 07:10:50 INFO - 'ts_paint', 07:10:50 INFO - 'tpaint', 07:10:50 INFO - 'sessionrestore', 07:10:50 INFO - 'sessionrestore_no_auto_restore', 07:10:50 INFO - 'tabpaint']}, 07:10:50 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['a11yr', 07:10:50 INFO - 'ts_paint', 07:10:50 INFO - 'tpaint', 07:10:50 INFO - 'sessionrestore', 07:10:50 INFO - 'sessionrestore_no_auto_restore', 07:10:50 INFO - 'tabpaint']}, 07:10:50 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['tsvgx', 07:10:50 INFO - 'tsvgr_opacity', 07:10:50 INFO - 'tart', 07:10:50 INFO - 'tscrollx', 07:10:50 INFO - 'cart']}, 07:10:50 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:10:50 INFO - 'tsvgr_opacity', 07:10:50 INFO - 'tart', 07:10:50 INFO - 'tscrollx', 07:10:50 INFO - 'cart']}, 07:10:50 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'talos_options': ['--disable-e10s'], 07:10:50 INFO - 'tests': ['tp5o']}, 07:10:50 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'tests': ['tp5o']}, 07:10:50 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'talos_options': ['--disable-e10s', 07:10:50 INFO - '--xperf_path', 07:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:10:50 INFO - 'tests': ['tp5n']}, 07:10:50 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:10:50 INFO - 'talos_options': ['--xperf_path', 07:10:50 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:10:50 INFO - 'tests': ['tp5n']}}, 07:10:50 INFO - 'talos.zip': {'path': '', 07:10:50 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:10:50 INFO - Running post-action listener: _resource_record_post_action 07:10:50 INFO - [mozharness: 2016-11-07 15:10:50.428833Z] Finished populate-webroot step (success) 07:10:50 INFO - [mozharness: 2016-11-07 15:10:50.428962Z] Running create-virtualenv step. 07:10:50 INFO - Running pre-action listener: _resource_record_pre_action 07:10:50 INFO - Running main action method: create_virtualenv 07:10:50 INFO - Creating virtualenv /builds/slave/test/build/venv 07:10:50 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 07:10:50 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:10:50 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:10:50 INFO - Using real prefix '/tools/python27' 07:10:50 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:10:51 INFO - Installing distribute.............................................................................................................................................................................................done. 07:10:54 INFO - Installing pip.................done. 07:10:54 INFO - Return code: 0 07:10:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 07:10:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 07:10:54 INFO - Reading from file tmpfile_stdout 07:10:54 INFO - Output received: 07:10:54 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) 07:10:54 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:10:54 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')]} 07:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:54 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')]} 07:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:54 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:10:54 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 07:10:54 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 07:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:54 INFO - 'HOME': '/Users/cltbld', 07:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:54 INFO - 'LOGNAME': 'cltbld', 07:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:54 INFO - 'MOZ_NO_REMOTE': '1', 07:10:54 INFO - 'NO_EM_RESTART': '1', 07:10:54 INFO - 'PAGER': '/bin/cat', 07:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:54 INFO - 'PWD': '/builds/slave/test', 07:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:54 INFO - 'SHELL': '/bin/bash', 07:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:10:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:54 INFO - 'USER': 'cltbld', 07:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:54 INFO - 'XPC_FLAGS': '0x0', 07:10:54 INFO - 'XPC_SERVICE_NAME': '0', 07:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:54 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 07:10:54 INFO - Cleaning up... 07:10:54 INFO - Return code: 0 07:10:54 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:10:54 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')]} 07:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:10:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:54 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')]} 07:10:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:10:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:10:54 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:10:54 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 07:10:54 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 07:10:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:10:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:10:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:10:54 INFO - 'HOME': '/Users/cltbld', 07:10:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:10:54 INFO - 'LOGNAME': 'cltbld', 07:10:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:10:54 INFO - 'MOZ_NO_REMOTE': '1', 07:10:54 INFO - 'NO_EM_RESTART': '1', 07:10:54 INFO - 'PAGER': '/bin/cat', 07:10:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:10:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:10:54 INFO - 'PWD': '/builds/slave/test', 07:10:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:10:54 INFO - 'SHELL': '/bin/bash', 07:10:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:10:54 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:10:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:10:54 INFO - 'USER': 'cltbld', 07:10:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:10:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:10:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:10:54 INFO - 'XPC_FLAGS': '0x0', 07:10:54 INFO - 'XPC_SERVICE_NAME': '0', 07:10:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:10:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:10:54 INFO - Downloading/unpacking psutil>=3.1.1 07:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:10:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:10:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:10:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:10:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:10:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:10:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:10:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:10:58 INFO - Installing collected packages: psutil 07:10:58 INFO - Running setup.py install for psutil 07:10:58 INFO - building 'psutil._psutil_osx' extension 07:10:58 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 07:10:59 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 07:10:59 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 07:10:59 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 07:10:59 INFO - building 'psutil._psutil_posix' extension 07:10:59 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 07:10:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:10:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:10:59 INFO - ^ 07:10:59 INFO - 1 warning generated. 07:10:59 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 07:11:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:11:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:11:00 INFO - Successfully installed psutil 07:11:00 INFO - Cleaning up... 07:11:00 INFO - Return code: 0 07:11:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:00 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:00 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 07:11:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 07:11:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:00 INFO - 'HOME': '/Users/cltbld', 07:11:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:00 INFO - 'LOGNAME': 'cltbld', 07:11:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:00 INFO - 'MOZ_NO_REMOTE': '1', 07:11:00 INFO - 'NO_EM_RESTART': '1', 07:11:00 INFO - 'PAGER': '/bin/cat', 07:11:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:00 INFO - 'PWD': '/builds/slave/test', 07:11:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:00 INFO - 'SHELL': '/bin/bash', 07:11:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:00 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:00 INFO - 'USER': 'cltbld', 07:11:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:00 INFO - 'XPC_FLAGS': '0x0', 07:11:00 INFO - 'XPC_SERVICE_NAME': '0', 07:11:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:11:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:11:03 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:11:03 INFO - Installing collected packages: mozsystemmonitor 07:11:03 INFO - Running setup.py install for mozsystemmonitor 07:11:03 INFO - Successfully installed mozsystemmonitor 07:11:03 INFO - Cleaning up... 07:11:03 INFO - Return code: 0 07:11:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:11:03 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')]} 07:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:03 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')]} 07:11:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:03 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:03 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 07:11:03 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 07:11:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:03 INFO - 'HOME': '/Users/cltbld', 07:11:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:03 INFO - 'LOGNAME': 'cltbld', 07:11:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:03 INFO - 'MOZ_NO_REMOTE': '1', 07:11:03 INFO - 'NO_EM_RESTART': '1', 07:11:03 INFO - 'PAGER': '/bin/cat', 07:11:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:03 INFO - 'PWD': '/builds/slave/test', 07:11:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:03 INFO - 'SHELL': '/bin/bash', 07:11:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:03 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:03 INFO - 'USER': 'cltbld', 07:11:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:03 INFO - 'XPC_FLAGS': '0x0', 07:11:03 INFO - 'XPC_SERVICE_NAME': '0', 07:11:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:03 INFO - Downloading/unpacking jsonschema==2.5.1 07:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:03 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:03 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:03 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:03 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:11:06 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:11:06 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 07:11:06 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 07:11:06 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 07:11:06 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 07:11:06 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 07:11:06 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 07:11:06 INFO - Downloading functools32-3.2.3-2.tar.gz 07:11:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:11:06 INFO - warning: no files found matching '*.txt' 07:11:06 INFO - no previously-included directories found matching 'build' 07:11:06 INFO - no previously-included directories found matching 'dist' 07:11:06 INFO - no previously-included directories found matching '.git*' 07:11:06 INFO - Installing collected packages: jsonschema, functools32 07:11:06 INFO - Running setup.py install for functools32 07:11:06 INFO - warning: no files found matching '*.txt' 07:11:06 INFO - no previously-included directories found matching 'build' 07:11:06 INFO - no previously-included directories found matching 'dist' 07:11:06 INFO - no previously-included directories found matching '.git*' 07:11:06 INFO - Successfully installed jsonschema functools32 07:11:06 INFO - Cleaning up... 07:11:06 INFO - Return code: 0 07:11:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:11:06 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')]} 07:11:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:06 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')]} 07:11:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:06 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:06 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 07:11:06 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 07:11:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:06 INFO - 'HOME': '/Users/cltbld', 07:11:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:06 INFO - 'LOGNAME': 'cltbld', 07:11:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:06 INFO - 'MOZ_NO_REMOTE': '1', 07:11:06 INFO - 'NO_EM_RESTART': '1', 07:11:06 INFO - 'PAGER': '/bin/cat', 07:11:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:06 INFO - 'PWD': '/builds/slave/test', 07:11:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:06 INFO - 'SHELL': '/bin/bash', 07:11:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:06 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:06 INFO - 'USER': 'cltbld', 07:11:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:06 INFO - 'XPC_FLAGS': '0x0', 07:11:06 INFO - 'XPC_SERVICE_NAME': '0', 07:11:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:07 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:11:07 INFO - Cleaning up... 07:11:07 INFO - Return code: 0 07:11:07 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:11:07 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')]} 07:11:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:07 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')]} 07:11:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:07 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:07 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 07:11:07 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 07:11:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:07 INFO - 'HOME': '/Users/cltbld', 07:11:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:07 INFO - 'LOGNAME': 'cltbld', 07:11:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:07 INFO - 'MOZ_NO_REMOTE': '1', 07:11:07 INFO - 'NO_EM_RESTART': '1', 07:11:07 INFO - 'PAGER': '/bin/cat', 07:11:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:07 INFO - 'PWD': '/builds/slave/test', 07:11:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:07 INFO - 'SHELL': '/bin/bash', 07:11:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:07 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:07 INFO - 'USER': 'cltbld', 07:11:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:07 INFO - 'XPC_FLAGS': '0x0', 07:11:07 INFO - 'XPC_SERVICE_NAME': '0', 07:11:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:07 INFO - Downloading/unpacking blobuploader==1.2.4 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 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 07:11:07 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 07:11:09 INFO - Downloading blobuploader-1.2.4.tar.gz 07:11:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:11:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:11:09 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 07:11:09 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 07:11:09 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 07:11:09 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 07:11:09 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 07:11:09 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 07:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:11:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:10 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:10 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:11:10 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:11:10 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:11:10 INFO - Downloading docopt-0.6.1.tar.gz 07:11:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:11:11 INFO - Installing collected packages: blobuploader, requests, docopt 07:11:11 INFO - Running setup.py install for blobuploader 07:11:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:11:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:11:11 INFO - Running setup.py install for requests 07:11:11 INFO - Running setup.py install for docopt 07:11:11 INFO - Successfully installed blobuploader requests docopt 07:11:11 INFO - Cleaning up... 07:11:11 INFO - Return code: 0 07:11:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:11 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:11 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 07:11:11 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 07:11:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:11 INFO - 'HOME': '/Users/cltbld', 07:11:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:11 INFO - 'LOGNAME': 'cltbld', 07:11:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:11 INFO - 'MOZ_NO_REMOTE': '1', 07:11:11 INFO - 'NO_EM_RESTART': '1', 07:11:11 INFO - 'PAGER': '/bin/cat', 07:11:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:11 INFO - 'PWD': '/builds/slave/test', 07:11:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:11 INFO - 'SHELL': '/bin/bash', 07:11:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:11 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:11 INFO - 'USER': 'cltbld', 07:11:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:11 INFO - 'XPC_FLAGS': '0x0', 07:11:11 INFO - 'XPC_SERVICE_NAME': '0', 07:11:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-mQemru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fBZDfo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-LBv2K5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-KXXooB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-JpV4q2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-C1NIRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-gOrxBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:12 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5rtFEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-9XPYXo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FHQbdB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1hmA5M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-7HLd3p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-VGhcid-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-D3j_ZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-tGjaaS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-ZjnRcS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:13 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-FHDZPW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:11:14 INFO - Running setup.py install for manifestparser 07:11:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:11:14 INFO - Running setup.py install for mozcrash 07:11:14 INFO - Running setup.py install for mozdebug 07:11:14 INFO - Running setup.py install for mozdevice 07:11:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:11:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:11:14 INFO - Running setup.py install for mozfile 07:11:14 INFO - Running setup.py install for mozhttpd 07:11:14 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:11:14 INFO - Running setup.py install for mozinfo 07:11:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Running setup.py install for mozInstall 07:11:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Running setup.py install for mozleak 07:11:15 INFO - Running setup.py install for mozlog 07:11:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Running setup.py install for moznetwork 07:11:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Running setup.py install for mozprocess 07:11:15 INFO - Running setup.py install for mozprofile 07:11:15 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:11:15 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for mozrunner 07:11:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Running setup.py install for mozscreenshot 07:11:16 INFO - Running setup.py install for moztest 07:11:16 INFO - Running setup.py install for mozversion 07:11:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:11:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:11:16 INFO - Cleaning up... 07:11:16 INFO - Return code: 0 07:11:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:16 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:16 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 07:11:16 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 07:11:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:16 INFO - 'HOME': '/Users/cltbld', 07:11:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:16 INFO - 'LOGNAME': 'cltbld', 07:11:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:16 INFO - 'MOZ_NO_REMOTE': '1', 07:11:16 INFO - 'NO_EM_RESTART': '1', 07:11:16 INFO - 'PAGER': '/bin/cat', 07:11:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:16 INFO - 'PWD': '/builds/slave/test', 07:11:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:16 INFO - 'SHELL': '/bin/bash', 07:11:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:16 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:16 INFO - 'USER': 'cltbld', 07:11:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:16 INFO - 'XPC_FLAGS': '0x0', 07:11:16 INFO - 'XPC_SERVICE_NAME': '0', 07:11:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:11:16 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-OPevz0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-8nktE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-5ioYcc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-qPjkW1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-CqDJoo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Drla4o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-waMNTN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-fpgPCE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-GNLa5V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-WxoGoD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:11:17 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)) 07:11:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:11:17 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-1U9Orm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:11:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-Z8rVAL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:11:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-YBv6WB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:11:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-vIdxPX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-dYWGi4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:11:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-RefmWQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:11:18 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)) 07:11:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:11:18 INFO - Running setup.py (path:/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/pip-9vgtyJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:11:18 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)) 07:11:18 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)) 07:11:18 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)) 07:11:18 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)) 07:11:18 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)) 07:11:18 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)) 07:11:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:11:18 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 07:11:18 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 07:11:18 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 07:11:18 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 07:11:18 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 07:11:18 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 07:11:21 INFO - Downloading blessings-1.6.tar.gz 07:11:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:11:21 INFO - Installing collected packages: blessings 07:11:21 INFO - Running setup.py install for blessings 07:11:21 INFO - Successfully installed blessings 07:11:21 INFO - Cleaning up... 07:11:21 INFO - Return code: 0 07:11:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:11:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:21 INFO - Reading from file tmpfile_stdout 07:11:21 INFO - Current package versions: 07:11:21 INFO - blessings == 1.6 07:11:21 INFO - blobuploader == 1.2.4 07:11:21 INFO - docopt == 0.6.1 07:11:21 INFO - functools32 == 3.2.3-2 07:11:21 INFO - jsonschema == 2.5.1 07:11:21 INFO - manifestparser == 1.1 07:11:21 INFO - mozInstall == 1.12 07:11:21 INFO - mozcrash == 0.17 07:11:21 INFO - mozdebug == 0.1 07:11:21 INFO - mozdevice == 0.48 07:11:21 INFO - mozfile == 1.2 07:11:21 INFO - mozhttpd == 0.7 07:11:21 INFO - mozinfo == 0.9 07:11:21 INFO - mozleak == 0.1 07:11:21 INFO - mozlog == 3.4 07:11:21 INFO - moznetwork == 0.27 07:11:21 INFO - mozprocess == 0.23 07:11:21 INFO - mozprofile == 0.28 07:11:21 INFO - mozrunner == 6.12 07:11:21 INFO - mozscreenshot == 0.1 07:11:21 INFO - mozsystemmonitor == 0.3 07:11:21 INFO - moztest == 0.7 07:11:21 INFO - mozversion == 1.4 07:11:21 INFO - psutil == 3.1.1 07:11:21 INFO - requests == 1.2.3 07:11:21 INFO - wsgiref == 0.1.2 07:11:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:21 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:21 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 07:11:21 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 07:11:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:21 INFO - 'HOME': '/Users/cltbld', 07:11:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:21 INFO - 'LOGNAME': 'cltbld', 07:11:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:21 INFO - 'MOZ_NO_REMOTE': '1', 07:11:21 INFO - 'NO_EM_RESTART': '1', 07:11:21 INFO - 'PAGER': '/bin/cat', 07:11:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:21 INFO - 'PWD': '/builds/slave/test', 07:11:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:21 INFO - 'SHELL': '/bin/bash', 07:11:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:21 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:21 INFO - 'USER': 'cltbld', 07:11:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:21 INFO - 'XPC_FLAGS': '0x0', 07:11:21 INFO - 'XPC_SERVICE_NAME': '0', 07:11:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 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)) 07:11:22 INFO - Cleaning up... 07:11:22 INFO - Return code: 0 07:11:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:11:22 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')]} 07:11:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:11:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:22 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')]} 07:11:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:11:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:11:22 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 0x10cf0e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ce84570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fcf9955ea20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, '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.vgedz3FKd3/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.2DOdEIFZrR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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 07:11:22 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 07:11:22 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 07:11:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:22 INFO - 'HOME': '/Users/cltbld', 07:11:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:22 INFO - 'LOGNAME': 'cltbld', 07:11:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:22 INFO - 'MOZ_NO_REMOTE': '1', 07:11:22 INFO - 'NO_EM_RESTART': '1', 07:11:22 INFO - 'PAGER': '/bin/cat', 07:11:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:22 INFO - 'PWD': '/builds/slave/test', 07:11:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:22 INFO - 'SHELL': '/bin/bash', 07:11:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:22 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:22 INFO - 'USER': 'cltbld', 07:11:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:22 INFO - 'XPC_FLAGS': '0x0', 07:11:22 INFO - 'XPC_SERVICE_NAME': '0', 07:11:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:11:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:11:22 INFO - Cleaning up... 07:11:22 INFO - Return code: 0 07:11:22 INFO - Running post-action listener: _resource_record_post_action 07:11:22 INFO - Running post-action listener: _start_resource_monitoring 07:11:22 INFO - Starting resource monitoring. 07:11:22 INFO - [mozharness: 2016-11-07 15:11:22.482064Z] Finished create-virtualenv step (success) 07:11:22 INFO - [mozharness: 2016-11-07 15:11:22.483116Z] Running install step. 07:11:22 INFO - Running pre-action listener: _resource_record_pre_action 07:11:22 INFO - Running main action method: install 07:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:11:22 INFO - Reading from file tmpfile_stdout 07:11:22 INFO - Detecting whether we're running mozinstall >=1.0... 07:11:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:11:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:11:22 INFO - Reading from file tmpfile_stdout 07:11:22 INFO - Output received: 07:11:22 INFO - Usage: mozinstall [options] installer 07:11:22 INFO - Options: 07:11:22 INFO - -h, --help show this help message and exit 07:11:22 INFO - -d DEST, --destination=DEST 07:11:22 INFO - Directory to install application into. [default: 07:11:22 INFO - "/builds/slave/test"] 07:11:22 INFO - --app=APP Application being installed. [default: firefox] 07:11:22 INFO - mkdir: /builds/slave/test/build/application 07:11:22 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'] 07:11:22 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 07:11:56 INFO - Reading from file tmpfile_stdout 07:11:56 INFO - Output received: 07:11:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:11:56 INFO - Running post-action listener: _resource_record_post_action 07:11:56 INFO - [mozharness: 2016-11-07 15:11:56.835239Z] Finished install step (success) 07:11:56 INFO - [mozharness: 2016-11-07 15:11:56.835423Z] Running run-tests step. 07:11:56 INFO - Running pre-action listener: _resource_record_pre_action 07:11:56 INFO - Running main action method: run_tests 07:11:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:11:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:11:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:11:56 INFO - 2016-11-07 07:11:56.850 screenresolution[1644:8664] starting screenresolution argv=screenresolution get 07:11:56 INFO - 2016-11-07 07:11:56.853 screenresolution[1644:8664] Display 0: 1600x1200x32@60 07:11:56 INFO - 2016-11-07 07:11:56.858 screenresolution[1645:8666] starting screenresolution argv=screenresolution list 07:11:56 INFO - Available Modes on Display 0 07:11:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:11:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:11:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:11:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:11:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:11:57 INFO - Intel Iris: 07:11:57 INFO - Chipset Model: Intel Iris 07:11:57 INFO - Type: GPU 07:11:57 INFO - Bus: Built-In 07:11:57 INFO - VRAM (Dynamic, Max): 1536 MB 07:11:57 INFO - Vendor: Intel (0x8086) 07:11:57 INFO - Device ID: 0x0a2e 07:11:57 INFO - Revision ID: 0x0009 07:11:57 INFO - Displays: 07:11:57 INFO - Display: 07:11:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:11:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:11:57 INFO - Display Serial Number: 1600x1200 60 07:11:57 INFO - Main Display: Yes 07:11:57 INFO - Mirror: Off 07:11:57 INFO - Online: Yes 07:11:57 INFO - Rotation: Supported 07:11:57 INFO - Return code: 0 07:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:11:57 INFO - Python 2.7.3 07:11:57 INFO - Return code: 0 07:11:57 INFO - grabbing minidump binary from tooltool 07:11:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:11:57 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 0x10cd22d30>, '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 0x10cb48db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10cd1ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:11:57 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 07:11:57 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 07:11:57 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 07:11:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:11:57 INFO - Return code: 0 07:11:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:11:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:11:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:11:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:11:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:11:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 07:11:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Cedar --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0104 --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 07:11:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.vgedz3FKd3/Render', 07:11:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:11:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:11:57 INFO - 'HOME': '/Users/cltbld', 07:11:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:11:57 INFO - 'LOGNAME': 'cltbld', 07:11:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:11:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:11:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:11:57 INFO - 'MOZ_NO_REMOTE': '1', 07:11:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:11:57 INFO - 'NO_EM_RESTART': '1', 07:11:57 INFO - 'PAGER': '/bin/cat', 07:11:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:11:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:11:57 INFO - 'PWD': '/builds/slave/test', 07:11:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:11:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:11:57 INFO - 'SHELL': '/bin/bash', 07:11:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.2DOdEIFZrR/Listeners', 07:11:57 INFO - 'TMPDIR': '/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/', 07:11:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:11:57 INFO - 'USER': 'cltbld', 07:11:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:11:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:11:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:11:57 INFO - 'XPC_FLAGS': '0x0', 07:11:57 INFO - 'XPC_SERVICE_NAME': '0', 07:11:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:11:57 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Cedar', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0104', '--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 07:11:57 INFO - mozversion application_buildid: 20161107041537 07:11:57 INFO - mozversion application_changeset: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 07:11:57 INFO - mozversion application_display_name: Nightly 07:11:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:11:57 INFO - mozversion application_name: Firefox 07:11:57 INFO - mozversion application_remotingname: firefox 07:11:57 INFO - mozversion application_repository: https://hg.mozilla.org/projects/cedar 07:11:57 INFO - mozversion application_vendor: Mozilla 07:11:57 INFO - mozversion application_version: 52.0a1 07:11:57 INFO - mozversion platform_buildid: 20161107041537 07:11:57 INFO - mozversion platform_changeset: 36b139f0c1300e703ab80a1ef58f56bbc55d87e4 07:11:57 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/cedar 07:11:57 INFO - mozversion platform_version: 52.0a1 07:11:57 INFO - using testdate: 1478531517 07:11:57 INFO - actual date: 1478531517 07:11:57 INFO - starting webserver on 'localhost:49271' 07:11:57 INFO - SUITE-START | Running 2 tests 07:11:57 INFO - TEST-START | dromaeo_css 07:11:57 INFO - operating with platform_type : mac_ 07:11:57 INFO - Initialising browser for dromaeo_css test... 07:11:57 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpnmUn6A/profile http://localhost:49271/getInfo.html) 07:12:00 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 07:12:00 INFO - PROCESS | 1655 | colorDepth:24 07:12:00 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 07:12:00 INFO - PROCESS | 1655 | __metrics 07:12:00 INFO - PROCESS | 1655 | 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" 07:12:00 INFO - PROCESS | 1655 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:12:00 INFO - TEST-INFO | 1655: exit 0 07:12:00 INFO - Browser initialized. 07:12:00 INFO - Running cycle 1/1 for dromaeo_css test... 07:12:00 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmpnmUn6A/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:12:12 INFO - PROCESS | 1659 | RSS: Main: 232005632 07:12:12 INFO - PROCESS | 1659 | 07:13:04 INFO - PROCESS | 1659 | 1478531584379 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] 07:15:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 07:15:18 INFO - PROCESS | 1659 | RSS: Main: 408174592 07:15:18 INFO - PROCESS | 1659 | 07:18:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 07:18:22 INFO - PROCESS | 1659 | RSS: Main: 371240960 07:18:22 INFO - PROCESS | 1659 | 07:21:24 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 07:21:25 INFO - PROCESS | 1659 | RSS: Main: 354263040 07:21:25 INFO - PROCESS | 1659 | 07:24:27 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 07:24:28 INFO - PROCESS | 1659 | RSS: Main: 348758016 07:24:28 INFO - PROCESS | 1659 | 07:27:32 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 07:27:32 INFO - PROCESS | 1659 | RSS: Main: 336330752 07:27:32 INFO - PROCESS | 1659 | 07:30:35 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 07:30:36 INFO - PROCESS | 1659 | RSS: Main: 546693120 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | __start_tp_report 07:30:36 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 07:30:36 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 07:30:36 INFO - PROCESS | 1659 | |i|pagename|runs| 07:30:36 INFO - PROCESS | 1659 | |0;dojo.html;3334.3164651246493;3363.327334533094;3363.882264120755;3385.9830700846524;3417.589889213293;5595.300587426572;5665.3768085510565;5712.542996560275;5769.8269051928455;5795.82612521624;4423.447069116361;4844.249141383091;5656.066748986423;5690.5447564194865;5696.231008813809;5136.92294615582;5151.742412879341;5166.767495462695;5190.71451070191;5255.789768409259;5161.406438259592;5223.955208958205;5282.102042652758;5325.3875804282425;5341.359036915556;5313.548348390372;5318.042752304584;5328.120860058086;5360.812371566996;5392.245085687987;243.96568713890076;5300.867478313035;5305.177697456919;5311.282976798121;5378.489043540857;3208.502181439907;5159.819406320798;5222.112240919053;5229.241759944786;5234.162533994542;5660.377358490563;5695.430456954271;5700.828975130711;5727.684977326246;5730.9426905730825;6283.497320214372;6286.151369565142;6297.118403423525;6312.368763123697;6312.463440607554;6120.294761999619;6135.3674730672865;6211.16149319845;6212.068189771525;6212.378762123797;5776.249087618583;5805.593608447452;5823.737931793037;5850.765969361263;5856.707164641751;6254.655993920287;6260.780872669435;6273.529485288622;6277.2781130170615;6279.340669229665;6016.458518733335;6078.787242446493;6080.847978800565;6178.907316390258;6183.907241391383;6182.721777520022;6208.906866397007;6248.562600618004;6266.342034086447;6298.779542715983;6195.411435913581;6209.565330426917;6213.751449941951;6242.968785156136;6247.843803904939;6122.7550897964475;6141.670122563672;6216.968915155395;6237.594556353823;6293.937060629427;4928.605711543068;4929.309896614477;4938.876528086755;4942.382202224722;4944.159492886218;4423.093265880487;4482.148391805546;4516.841910533181;4517.548245175456;4518.277075667943;5739.741711622987;5798.333191682926;5800.187973683687;5824.88350232993;5840.503557197686;6206.565540412218;6216.036514340284;6217.782377616762;6222.160008398899;6225.750969961151;5785.82642520725;5820.476157145878;5827.79602713903;5830.504407125357;5838.241028666331;5403.270558474555;5427.294451721251;5432.049391356497;5438.945610543923;5445.809396671177;6272.5922815016875;6311.037589189555;6344.58760180596;6362.172917520693;6374.681265936684;6269.937300626935;6311.905321420274;6315.431611155018;6316.210473690789;6339.207599050119;6219.445138715321;6224.564280500322;6262.123302737711;6271.937280627135;6292.433680968735;6150.200473938538;6161.599496032743;6172.22847144107;6183.5671502994355;6199.132121503083;6186.969065154645;6265.185525881606;6280.937190628035;6323.936760632335;6331.176946996861;5971.104334349882;6051.122587224916;6059.21230240066;6137.539684523768;6145.0782382642965;5981.491573216325;6083.756649733961;6090.9390906090375;6099.969500152648;6151.800398922346;5999.430054144849;5999.790007349722;6002;6017.217761690952;6025.487833534024;5993.9700301498215;6050.757969681164;6147.047207682816;6153.6923153841235;6168.814935551941;5838.241028666246;5919.970400147971;5926.970365148147;5936.673482958478;5947.524198064231 07:30:36 INFO - PROCESS | 1659 | |1;ext.html;508.80919655129327;514.6963291657921;519.2470917170104;524.0828550037435;529.4705294705295;29804.850975745117;30347;31485.842570787143;38898.8055059725;39980.60019399812;40855.59144408555;41125.7943710281;41141.177176456455;41209;41266;9386.061393860628;9540.713778586654;9560.61757529698;9575.281853860954;9587.185089267421;8567.828643427129;8742.650293988232;9313.394629349104;9334.626614935429;11281;11122.277051991636;11198.38408887511;11225.60710375137;11252.774944501105;11270.549178032868;11342.716432089223;11397.943010284895;11408.771824563546;11410.71473213168;11410.942945285262;8455.74632761012;8702.695405660836;10990;11251.943740281327;11293.20947533673;38323.23353532942;38892.805535972424;38945.805270973746;38946;38985;3285.704286614216;3383.4924761285756;3423.3666771647327;3542.079059444537;3662.2675464907124;29997.400051998837;30195.396092078256;30212.54681179784;30298;30378.240543986358;3097.6902309769066;3136.702013308732;3291.1772056985756;3329.6004479462376;3336;459116;459170;460225;460305;477135.61432192964;54813.451865481635;55137.89724205493;55190;55400;55476.44523554794;57532.13701794476;57574.42425575691;57584.712076440184;57620.847583048104;57645;12604;15522.844771552365;15527.301271442811;15534.378624854879;15539.84460155384;12158.635240942785;12195.207311524722;12202.14584979043;12213.511459541696;12216.450259738156;13156.473741050251;13451.730965380637;14507.709845803025;14932.626684332761;14987.775183372258;6456.838579035561;6697.933020669829;6916.619585922823;7059.71761129549;7147.8570428592175;794.3049831397527;910.717677519971;930.7626555228373;965.8696076029646;1019.7144799456164;28648.14055578336;28838;29234.707652923626;29415.705842941723;29554;933.2767105493297;1019.7246743379246;1020.1277907389075;1092.5575142067476;1119.160629527854;14595.270236488133;14939;15047.172405517582;15315.927885048162;15352.622423066907;625383;628461;630584;631415.8429207916;634570;47640.80897977509;48187.759061204466;48366;53819.46180538145;54867.45132548703;1280.7886698694797;1322.735452909422;1328.4686125549858;1331.800229965513;1339.8660133986914;29768.10695679133;29803.552946705382;30222.217820997594;30285.545716814708;30383.088507344815;47273;47794.28308575444;47938;48045.47477040185;48057;537101;617062.9146854237;620189;635434;781462;21371.46571335729;22656.66015009809;24274.02903883825;24659.753402465747;24755.504889901742;43621.781891090344;43942.34086488641;44590.55409445864;44764.32853507135;44986.55013449823;36813.50271724182;37360;38311;38666.6133338663;40053.599464006154;36883;37089.25821483501;37555.2488950214;38318.42522362111;38426.23147537089;36368.52455751683;37192.69825556093;37280.254394911404;37391.81304093462;37657.24685506329;32350.838245809562;33095.84164554229;33782.83108584442;33786.3242735139;33811.492827607115;24586.631200531647;24812.50374992454;24874.378140546632;24975.87512062428;25167 07:30:36 INFO - PROCESS | 1659 | |2;jquery.html;346.52699065775226;364.6827260283554;367.6690978119694;375.48933450507315;396.2174704957707;14297.714045719093;14827.555173344803;15548.06711597305;19573.706394404096;20145.899270503625;18544.814551854477;19192.9040354798;19526.414207573795;19754.814711117306;19790.70313945292;5372.516473517392;6000.969995150018;6009.4891934185425;6015.909761353583;6018.6689732064715;5604.915926261089;5823.737931793058;5856.853578660546;5896.911546326808;5900.085486749561;5735.713214339266;5790.7104644767805;5871;5877.52979761618;5879.706014699269;5524.33707955046;5758.654480731169;5812.099124635688;5821;5824.184614154022;4824.951750482485;5044.344235249431;5053.570446512014;5055.545000949895;5073.213651883964;23624;23869.522609547887;24057.639135412985;24214.757852421426;24344.02623895042;2243.5176437065948;2383.9404014899765;2451.509698060377;2624.8818803153904;2730.918107239206;14662.340194691264;15335.079895206323;15378;15504.612384690474;15611.985220960714;1760.4278609451978;1770.2741875830977;1817.6546456173417;1828.882388410764;1966.9704954425695;104725.47637261842;120070.3996480012;120437.39781301125;123609.38195309056;125405.74594254032;8736.606852691644;10065.547050382751;10420.218483613759;10434.739131521621;10447.477626118663;12705.17416367933;12745;12934.870651293366;12944;12974.469978354131;8103.756887293391;8243.917560824435;8254.8218935709;8296;8364.289035432057;6964.756233531802;8302.501849889026;8312.70905518316;8503.61733721984;8507.489550626982;5439.347278326626;5918.934591773522;6022.488088512438;6172.382761723881;6184.103305020747;1795.6857549928711;1802.9549261268312;1813.4740925131828;1819.9909000454913;1851.8425933795509;831.7754206364368;870.8693695945532;923.3472084967966;975.6341371985505;1060.8143574874366;19218.615627687366;20046.997650117435;20396;20917.477063073242;21351;840.3865178419666;885.526243459746;889.7001251764133;943.5423819447562;944.6740874398322;9293.395929264576;10260.948695256626;10635.202359823044;10672.359658420519;10713.928607139223;588696;597799;600661;619140;622597;66044;81598.36803263902;106192;130336.69663303245;131326;928.6749637626776;968.7771812483169;1006.9697909062739;1009.8182327181178;1010.2625083688948;17957.461276161735;18357.632847343244;18401;18464.630707386044;18466.81533184651;34232.657673422946;54290.728546357015;54803.903921920544;56690;56973;76744.84882726875;79751.60124199341;85266.1473385283;86586.56706716426;88223;7424.836861967132;7536.434767392577;7757.340626046849;7825.726099586487;7922.960385198037;10680.83978740304;11586.942065289619;11756.11829112837;12359.62921112368;12428.378581070729;9408.200302974325;10226.539805708611;10458;10905.672829814801;10989.945050274697;9561.709169262338;9889.258305626963;10483.633072842415;10691.679249622524;10862.782744344911;10441.477926103817;10599.099076578268;11071.05895998817;11124.666260012213;11213.943930280297;7608.353289970193;7693.846123077619;7737.767866964;7813.648385822538;8033.959830200812;9443.811123777623;10563.419011954416;10595.311304765166;10867.13062954946;10928.289661172317 07:30:36 INFO - PROCESS | 1659 | |3;mootools.html;1473.0646039764747;1475.8819294456441;1668.2826384654593;1745.9388921387754;1848.64875673622;7766.262205090515;8212.83574328513;8216.465929714564;8229.300509456696;8244.587770611475;7228.4217262618995;7523.172451030382;7544.660490277937;7600.20197879223;7610.16288208298;1945.8540609454349;2051.107768120865;2089.3836318286067;2168.392850002002;2275.931722048333;2269.217120093565;2308.2613563659634;2309.9538009239805;2311.3759284993125;2317.79139877411;1927.633749587574;2219.3009202101366;2288.347820871052;2298.2415802785126;2302.8157747380255;2254.5265494246146;2259.6949411829355;2274.283600665793;2275.112706044644;2288.313505948209;2287.1880482428664;2310.318456055468;2312.629979203319;2317.3279748872806;2323.953520929572;7543.717568013451;7774.805629859242;8103.5948202590225;8122.75631731049;8195.34437245019;4932.8273510427325;5099.311592934943;5102;5102.081625307444;5104.3619547556555;3094.932248374307;3165.4618714818307;3406.097384193191;3430.6740859618294;3494.091536200581;859.5143743784741;1008.7478130467383;1042.405828677654;1061.5753698520575;1123.690984979129;10266.845997310047;10427.947860260727;10503.999999999924;10546.419946902917;10553.261271710984;4250.404943307939;4340.8697739067875;4507.414036175342;4514.796834142471;4554.931676024862;9167.587458564381;9175.816483670424;9178.586963586655;9185.127412895765;9194.724158275263;5078.415982162025;5086.618503612244;5092.6180536459915;5094.643374963717;5096.719680417538;4468.754218518013;4579.931301030487;4580.55712450577;4609.562091601292;4681.087187998308;3080.4609193391516;3512.3853325667906;3636.654517820803;3654.104744337654;3795.525559305087;1037.7094413564214;1235.3144005077268;1235.6601934467913;1247.7317376763858;1248.8001919692806;848.117957324388;862.4221771413169;867.2931560777946;913.59345091435;919.2691810010923;5528.917066244009;5645.943540564542;5787.942120578825;5831.241938547962;5991.191189189403;880.7577916073132;893.860328081701;908.3777612335572;915.4370062411568;956.7942892278194;3390.965755444613;3485.634008429129;3747.5877653458097;3841.654251117413;3914.412838074312;9253.444793312423;9457.243420526342;9644.03559644044;9802.166815820592;9820.901790982141;8530.976282845973;9522.14300712939;9561.378510396802;9741.220702343795;9789.510524473748;7465.253474652492;7586.468947173756;7656.464047516621;7668.271514206142;7693.615319234016;5389.164679474688;5510.173473978935;5515.751791169327;5517.806876759294;5593.021221286177;8977.551122443982;9026.413283136837;9026.45841249527;9146.58840352199;9252.49111298885;9136.6802161924;9212.216961558343;9270.675526356545;9323.20752736025;9359.485228312507;6941.791746247621;7069.787906362817;7081.964590177016;7082.362587367162;7110.893336599852;7385.409167266714;7596.392288616787;7680.615969201406;7684.077910650758;7700.191479894755;6593.637349945799;6682.198136223684;6874.415674667509;6881.554873476426;7038.929610704033;6578.177727784027;6591.736330546724;6744.0895479109695;6770.390664840188;6849.726010959505;6481.5138864584405;6545.607263564201;6566.770163044271;6567.9671601641685;6570.244421891543;4607.562281583244;4759.76201189946;4788.16207163738;4803.159447096674;4868.026394721141;4653.650976176868;4743.525647435229;4840.419149702058;4875;4984.352034235526 07:30:36 INFO - PROCESS | 1659 | |4;prototype.html;296.6499530553946;297.3369386268621;307.1246946203321;310.83215063865515;310.9751219902408;5047.343845300106;5248.685078895269;5282.860441521185;5296.569926119945;5315.325672413187;3867.883963481093;4537.659675524341;5143.3570803649545;5152.69083854969;5157.638965272433;3899.512560929884;4013.5183777946686;4026.932862791363;4027.5166979962446;4033.479075577764;3252.2682396460846;4004.1591265834063;4009.3985902114623;4018.5981401859726;4028.415879697442;3817.950063732468;3995.2808494470983;3998.3002974479496;4017.3973903914352;4047.7571345719202;3473.8089405082965;3536.9292614147703;3970.54338751043;3997.4023378958877;4018.9196216075807;2749.766269867064;2899.5215789394724;3469.259285164717;3482.8258587070545;3484.529588505545;4601.056783359403;4857.467624328029;5152.742362881841;5156.303898973628;5188.273641690166;4177.248095342868;4399.724080016791;4562.452505699337;4564.771761411949;4569.8750081212065;5549.278593782823;5557.777688892439;5569.805056822992;5571.997040532702;5581.325602319329;3125.828079455629;3264.9673503264903;3369.275605744777;3787.9621203787883;3810.371288737355;4364.192624364471;5072.467390923936;5108.923366149511;5295.4969277918535;5676.432356764331;3641.1807343348064;3843.7501562398356;3845.3655146901006;3849.653531182207;3856.305864944281;3678.2643471305846;3903.828851344642;3906.6679332257077;3915.353966595537;3920.921581568353;4015.598440155961;4095.9180816383505;4388.890277743082;4510.797014134372;4541.795619197144;5171.3018742469285;5570.136628822539;5574.777008919597;5593.636963168735;5624.831255062355;1083.766990097133;1085.7557049663762;1202.212550779241;1208.3897631695938;1208.9214201076902;841.9036598868772;854.5513605357113;857.8241460500583;862.5810541658936;863.9481631102154;4527.796249168795;4561.521040290721;4928.654994150375;4937.481564435754;4960.255961605788;5165.354330708661;5317.931782186553;5368.7047212403695;5374.3013408256675;5416.214648875891;4086;4197.8320867165585;4893.290472881484;4903.975480122576;4918.655694101379;2509.4228327484416;2786;2804.775617950559;2821.2946763309174;2826.321682796114;5193.780250847949;5446.809361672323;5465.316835395575;5530.768961744269;5606.608002158717;4893.568139365301;5216.739163041909;5483.396826349099;5486.0125177468435;5503.889922201533;4296.014270082325;4367.781610919441;4983.8504844854715;5057.8735531612;5633.450925916728;4445.199864024377;4662.110483333771;4993.579398112304;5113.897722045613;5353.063213937623;4915.5330243626795;5270.920936185884;5273.574155888181;5293.338332708411;5316.680999140064;4482.775861206992;5521.4478552144465;5542.697466095384;5548.916766248591;5586.748596313095;3078.7383072438197;3209.5185722141864;3588.474898168216;3595.17311018462;3615.457681347819;4440.422745043253;5100.719460429712;5269.2348063399195;5301.575873929999;5352.571794256372;5170.948290517197;5187.221916712372;5200.60995425337;5218.9739051304505;5229.9801538699585;4617.353570500066;4735.437305689184;4889.315495830516;4942.901141977069;4993.550580447777;4328.718633288826;4368.890777730582;4572.588467037983;4941.616347422728;4980.576650984707;4962.751862406937;5032.943081952831;5329.9467005331;5497.120460726264;5504.4770746778995;5450.509454149145;5467.5625949923115;5478.685115572313;5498.752556135064;5509.201165830852 07:30:36 INFO - PROCESS | 1659 | |5;yui.html;715.9511315922176;716.0905649824723;757.9052618422697;782.0360832445034;792.845395147946;14976.92511537442;15217.695646087099;15244.237788110606;15254.694906101871;15269;13559.983001274895;13688.17870927745;13707.45170193191;13723.245221512812;13726.794098088536;659.8944168932971;673.7574473189648;676.3777324861139;806.7176488229117;831.584207896052;819.7171975668385;825.9628316725732;833.8457385383693;835.5947365527715;835.9205875441824;885.3183049052258;885.4067774591007;886.2909672262165;888.3071204460531;888.564603344363;691.837418206721;711.8006958051753;876.4040452492303;876.4434584039149;877.6533269358631;629.6914511889187;648.3160265919453;649.7758273395671;650.1840190560863;652.3834975947731;13486.12340197884;13497.595072147851;13511.594652160451;13535.796963045563;13539.864601354057;13157.671058223525;13331.933340333333;13471.191728496322;13471.854892334164;13494.190348579117;9888.47360741907;12210.57262995791;12277.508899644003;12294.446749896186;12395.504179832797;4331.675124365685;4369;4397.956020439787;4560.8859778505475;4577.107464044512;88537.55731221367;90211;93328.06671933262;93590.12819743567;94973;10504.474776261157;10564.049235568837;10585.259031867696;10613.310134841211;10951.34291942486;18462;19276;20625.896870515553;20690.068946897427;20739.58520829575;7614.619269036526;7769.53382797034;7863.444380576336;7867.685292588232;7992.720254791171;4345.239583073013;4367.432233809584;4406.25093734066;4444.333349997526;4469.821207151743;3393.5927688677516;3502.649735026477;3511.2626348467106;3511.3328467591077;3511.894643160709;651.3811878715239;651.5341530805497;658.1674182159572;659.5416185750953;662.8276648071536;513.7208351205546;655.8163714160042;666.5767237803932;670.6311528659218;675.5136301862651;7404.296591823768;8896.8665470018;9145.451272923647;9227.123423274777;9303.860442093373;635.2408871398727;645.4868379638199;666.9599824010478;667.6761770541284;669.2270427656065;4095.959040409558;4511.639068874482;4516.187086324493;4551.294549344857;4614.492405835355;94462.52768736113;112036;113319.43340283411;114391.42804285925;125103.12345314828;39901.60098399037;63145.368546314865;63278.101656949446;78409.60795195987;85074;56881.43118568844;76554.61722691351;82705.93235169169;83298.58350708208;84293;8484.703035393732;9523.857142142735;9568.712938611852;9622.663206787729;9648.855267170858;52399;52660.73669631627;54179;54478.910421792134;55846.16230756623;62754.372456274854;76580;77531.61234194018;77713;79368.60315698385;11954.521819127485;12307.938460307641;12351.69120771988;12394.380280985733;12703;13717.314134293445;13776;14009.159450433006;14013.43946242139;14317.069390489585;12872.806907896576;12881.935590321988;13274.734505310033;13563.829787233995;13713.45146194181;13009;13018.414171362501;13030.153040052366;13065.542706005244;13554.118982266122;12657.066038818313;13106.868931310564;13140.60578182656;13157.802632960706;13419.928420448157;11152.721181970515;11162.720931976766;11184.776304474028;11309.193305306275;11600.477978490819;9589.369807132665;10161.898381016095;10724.8927510727;11120.944395277971;11233.01620261755 07:30:36 INFO - PROCESS | 1659 | __end_tp_report 07:30:36 INFO - PROCESS | 1659 | __start_cc_report 07:30:36 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,1800 07:30:36 INFO - PROCESS | 1659 | __end_cc_report 07:30:36 INFO - PROCESS | 1659 | __startTimestamp1478532636118__endTimestamp 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | ------- Summary: start ------- 07:30:36 INFO - PROCESS | 1659 | Number of tests: 6 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5712.77 Median:5993.97 stddev:791.77 (13.2%) stddev-sans-first:771.90 07:30:36 INFO - PROCESS | 1659 | Values: 3334.3 3363.3 3363.9 3386.0 3417.6 5595.3 5665.4 5712.5 5769.8 5795.8 4423.4 4844.2 5656.1 5690.5 5696.2 5136.9 5151.7 5166.8 5190.7 5255.8 5161.4 5224.0 5282.1 5325.4 5341.4 5313.5 5318.0 5328.1 5360.8 5392.2 244.0 5300.9 5305.2 5311.3 5378.5 3208.5 5159.8 5222.1 5229.2 5234.2 5660.4 5695.4 5700.8 5727.7 5730.9 6283.5 6286.2 6297.1 6312.4 6312.5 6120.3 6135.4 6211.2 6212.1 6212.4 5776.2 5805.6 5823.7 5850.8 5856.7 6254.7 6260.8 6273.5 6277.3 6279.3 6016.5 6078.8 6080.8 6178.9 6183.9 6182.7 6208.9 6248.6 6266.3 6298.8 6195.4 6209.6 6213.8 6243.0 6247.8 6122.8 6141.7 6217.0 6237.6 6293.9 4928.6 4929.3 4938.9 4942.4 4944.2 4423.1 4482.1 4516.8 4517.5 4518.3 5739.7 5798.3 5800.2 5824.9 5840.5 6206.6 6216.0 6217.8 6222.2 6225.8 5785.8 5820.5 5827.8 5830.5 5838.2 5403.3 5427.3 5432.0 5438.9 5445.8 6272.6 6311.0 6344.6 6362.2 6374.7 6269.9 6311.9 6315.4 6316.2 6339.2 6219.4 6224.6 6262.1 6271.9 6292.4 6150.2 6161.6 6172.2 6183.6 6199.1 6187.0 6265.2 6280.9 6323.9 6331.2 5971.1 6051.1 6059.2 6137.5 6145.1 5981.5 6083.8 6090.9 6100.0 6151.8 5999.4 5999.8 6002.0 6017.2 6025.5 5994.0 6050.8 6147.0 6153.7 6168.8 5838.2 5920.0 5927.0 5936.7 5947.5 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:69748.07 Median:37140.98 stddev:156636.24 (421.7%) stddev-sans-first:156989.38 07:30:36 INFO - PROCESS | 1659 | Values: 508.8 514.7 519.2 524.1 529.5 29804.9 30347.0 31485.8 38898.8 39980.6 40855.6 41125.8 41141.2 41209.0 41266.0 9386.1 9540.7 9560.6 9575.3 9587.2 8567.8 8742.7 9313.4 9334.6 11281.0 11122.3 11198.4 11225.6 11252.8 11270.5 11342.7 11397.9 11408.8 11410.7 11410.9 8455.7 8702.7 10990.0 11251.9 11293.2 38323.2 38892.8 38945.8 38946.0 38985.0 3285.7 3383.5 3423.4 3542.1 3662.3 29997.4 30195.4 30212.5 30298.0 30378.2 3097.7 3136.7 3291.2 3329.6 3336.0 459116.0 459170.0 460225.0 460305.0 477135.6 54813.5 55137.9 55190.0 55400.0 55476.4 57532.1 57574.4 57584.7 57620.8 57645.0 12604.0 15522.8 15527.3 15534.4 15539.8 12158.6 12195.2 12202.1 12213.5 12216.5 13156.5 13451.7 14507.7 14932.6 14987.8 6456.8 6697.9 6916.6 7059.7 7147.9 794.3 910.7 930.8 965.9 1019.7 28648.1 28838.0 29234.7 29415.7 29554.0 933.3 1019.7 1020.1 1092.6 1119.2 14595.3 14939.0 15047.2 15315.9 15352.6 625383.0 628461.0 630584.0 631415.8 634570.0 47640.8 48187.8 48366.0 53819.5 54867.5 1280.8 1322.7 1328.5 1331.8 1339.9 29768.1 29803.6 30222.2 30285.5 30383.1 47273.0 47794.3 47938.0 48045.5 48057.0 537101.0 617062.9 620189.0 635434.0 781462.0 21371.5 22656.7 24274.0 24659.8 24755.5 43621.8 43942.3 44590.6 44764.3 44986.6 36813.5 37360.0 38311.0 38666.6 40053.6 36883.0 37089.3 37555.2 38318.4 38426.2 36368.5 37192.7 37280.3 37391.8 37657.2 32350.8 33095.8 33782.8 33786.3 33811.5 24586.6 24812.5 24874.4 24975.9 25167.0 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34420.56 Median:18481.79 stddev:100737.09 (545.1%) stddev-sans-first:100987.19 07:30:36 INFO - PROCESS | 1659 | Values: 346.5 364.7 367.7 375.5 396.2 14297.7 14827.6 15548.1 19573.7 20145.9 18544.8 19192.9 19526.4 19754.8 19790.7 5372.5 6001.0 6009.5 6015.9 6018.7 5604.9 5823.7 5856.9 5896.9 5900.1 5735.7 5790.7 5871.0 5877.5 5879.7 5524.3 5758.7 5812.1 5821.0 5824.2 4825.0 5044.3 5053.6 5055.5 5073.2 23624.0 23869.5 24057.6 24214.8 24344.0 2243.5 2383.9 2451.5 2624.9 2730.9 14662.3 15335.1 15378.0 15504.6 15612.0 1760.4 1770.3 1817.7 1828.9 1967.0 104725.5 120070.4 120437.4 123609.4 125405.7 8736.6 10065.5 10420.2 10434.7 10447.5 12705.2 12745.0 12934.9 12944.0 12974.5 8103.8 8243.9 8254.8 8296.0 8364.3 6964.8 8302.5 8312.7 8503.6 8507.5 5439.3 5918.9 6022.5 6172.4 6184.1 1795.7 1803.0 1813.5 1820.0 1851.8 831.8 870.9 923.3 975.6 1060.8 19218.6 20047.0 20396.0 20917.5 21351.0 840.4 885.5 889.7 943.5 944.7 9293.4 10260.9 10635.2 10672.4 10713.9 588696.0 597799.0 600661.0 619140.0 622597.0 66044.0 81598.4 106192.0 130336.7 131326.0 928.7 968.8 1007.0 1009.8 1010.3 17957.5 18357.6 18401.0 18464.6 18466.8 34232.7 54290.7 54803.9 56690.0 56973.0 76744.8 79751.6 85266.1 86586.6 88223.0 7424.8 7536.4 7757.3 7825.7 7923.0 10680.8 11586.9 11756.1 12359.6 12428.4 9408.2 10226.5 10458.0 10905.7 10989.9 9561.7 9889.3 10483.6 10691.7 10862.8 10441.5 10599.1 11071.1 11124.7 11213.9 7608.4 7693.8 7737.8 7813.6 8034.0 9443.8 10563.4 10595.3 10867.1 10928.3 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5287.45 Median:5512.96 stddev:2862.64 (51.9%) stddev-sans-first:2856.32 07:30:36 INFO - PROCESS | 1659 | Values: 1473.1 1475.9 1668.3 1745.9 1848.6 7766.3 8212.8 8216.5 8229.3 8244.6 7228.4 7523.2 7544.7 7600.2 7610.2 1945.9 2051.1 2089.4 2168.4 2275.9 2269.2 2308.3 2310.0 2311.4 2317.8 1927.6 2219.3 2288.3 2298.2 2302.8 2254.5 2259.7 2274.3 2275.1 2288.3 2287.2 2310.3 2312.6 2317.3 2324.0 7543.7 7774.8 8103.6 8122.8 8195.3 4932.8 5099.3 5102.0 5102.1 5104.4 3094.9 3165.5 3406.1 3430.7 3494.1 859.5 1008.7 1042.4 1061.6 1123.7 10266.8 10427.9 10504.0 10546.4 10553.3 4250.4 4340.9 4507.4 4514.8 4554.9 9167.6 9175.8 9178.6 9185.1 9194.7 5078.4 5086.6 5092.6 5094.6 5096.7 4468.8 4579.9 4580.6 4609.6 4681.1 3080.5 3512.4 3636.7 3654.1 3795.5 1037.7 1235.3 1235.7 1247.7 1248.8 848.1 862.4 867.3 913.6 919.3 5528.9 5645.9 5787.9 5831.2 5991.2 880.8 893.9 908.4 915.4 956.8 3391.0 3485.6 3747.6 3841.7 3914.4 9253.4 9457.2 9644.0 9802.2 9820.9 8531.0 9522.1 9561.4 9741.2 9789.5 7465.3 7586.5 7656.5 7668.3 7693.6 5389.2 5510.2 5515.8 5517.8 5593.0 8977.6 9026.4 9026.5 9146.6 9252.5 9136.7 9212.2 9270.7 9323.2 9359.5 6941.8 7069.8 7082.0 7082.4 7110.9 7385.4 7596.4 7680.6 7684.1 7700.2 6593.6 6682.2 6874.4 6881.6 7038.9 6578.2 6591.7 6744.1 6770.4 6849.7 6481.5 6545.6 6566.8 6568.0 6570.2 4607.6 4759.8 4788.2 4803.2 4868.0 4653.7 4743.5 4840.4 4875.0 4984.4 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4298.65 Median:4891.30 stddev:1313.23 (26.8%) stddev-sans-first:1282.10 07:30:36 INFO - PROCESS | 1659 | Values: 296.6 297.3 307.1 310.8 311.0 5047.3 5248.7 5282.9 5296.6 5315.3 3867.9 4537.7 5143.4 5152.7 5157.6 3899.5 4013.5 4026.9 4027.5 4033.5 3252.3 4004.2 4009.4 4018.6 4028.4 3818.0 3995.3 3998.3 4017.4 4047.8 3473.8 3536.9 3970.5 3997.4 4018.9 2749.8 2899.5 3469.3 3482.8 3484.5 4601.1 4857.5 5152.7 5156.3 5188.3 4177.2 4399.7 4562.5 4564.8 4569.9 5549.3 5557.8 5569.8 5572.0 5581.3 3125.8 3265.0 3369.3 3788.0 3810.4 4364.2 5072.5 5108.9 5295.5 5676.4 3641.2 3843.8 3845.4 3849.7 3856.3 3678.3 3903.8 3906.7 3915.4 3920.9 4015.6 4095.9 4388.9 4510.8 4541.8 5171.3 5570.1 5574.8 5593.6 5624.8 1083.8 1085.8 1202.2 1208.4 1208.9 841.9 854.6 857.8 862.6 863.9 4527.8 4561.5 4928.7 4937.5 4960.3 5165.4 5317.9 5368.7 5374.3 5416.2 4086.0 4197.8 4893.3 4904.0 4918.7 2509.4 2786.0 2804.8 2821.3 2826.3 5193.8 5446.8 5465.3 5530.8 5606.6 4893.6 5216.7 5483.4 5486.0 5503.9 4296.0 4367.8 4983.9 5057.9 5633.5 4445.2 4662.1 4993.6 5113.9 5353.1 4915.5 5270.9 5273.6 5293.3 5316.7 4482.8 5521.4 5542.7 5548.9 5586.7 3078.7 3209.5 3588.5 3595.2 3615.5 4440.4 5100.7 5269.2 5301.6 5352.6 5170.9 5187.2 5200.6 5219.0 5230.0 4617.4 4735.4 4889.3 4942.9 4993.6 4328.7 4368.9 4572.6 4941.6 4980.6 4962.8 5032.9 5329.9 5497.1 5504.5 5450.5 5467.6 5478.7 5498.8 5509.2 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:19739.66 Median:4569.00 stddev:28484.95 (623.4%) stddev-sans-first:28529.04 07:30:36 INFO - PROCESS | 1659 | Values: 716.0 716.1 757.9 782.0 792.8 14976.9 15217.7 15244.2 15254.7 15269.0 13560.0 13688.2 13707.5 13723.2 13726.8 659.9 673.8 676.4 806.7 831.6 819.7 826.0 833.8 835.6 835.9 885.3 885.4 886.3 888.3 888.6 691.8 711.8 876.4 876.4 877.7 629.7 648.3 649.8 650.2 652.4 13486.1 13497.6 13511.6 13535.8 13539.9 13157.7 13331.9 13471.2 13471.9 13494.2 9888.5 12210.6 12277.5 12294.4 12395.5 4331.7 4369.0 4398.0 4560.9 4577.1 88537.6 90211.0 93328.1 93590.1 94973.0 10504.5 10564.0 10585.3 10613.3 10951.3 18462.0 19276.0 20625.9 20690.1 20739.6 7614.6 7769.5 7863.4 7867.7 7992.7 4345.2 4367.4 4406.3 4444.3 4469.8 3393.6 3502.6 3511.3 3511.3 3511.9 651.4 651.5 658.2 659.5 662.8 513.7 655.8 666.6 670.6 675.5 7404.3 8896.9 9145.5 9227.1 9303.9 635.2 645.5 667.0 667.7 669.2 4096.0 4511.6 4516.2 4551.3 4614.5 94462.5 112036.0 113319.4 114391.4 125103.1 39901.6 63145.4 63278.1 78409.6 85074.0 56881.4 76554.6 82705.9 83298.6 84293.0 8484.7 9523.9 9568.7 9622.7 9648.9 52399.0 52660.7 54179.0 54478.9 55846.2 62754.4 76580.0 77531.6 77713.0 79368.6 11954.5 12307.9 12351.7 12394.4 12703.0 13717.3 13776.0 14009.2 14013.4 14317.1 12872.8 12881.9 13274.7 13563.8 13713.5 13009.0 13018.4 13030.2 13065.5 13554.1 12657.1 13106.9 13140.6 13157.8 13419.9 11152.7 11162.7 11184.8 11309.2 11600.5 9589.4 10161.9 10724.9 11120.9 11233.0 07:30:36 INFO - PROCESS | 1659 | -------- Summary: end -------- 07:30:36 INFO - PROCESS | 1659 | 07:30:36 INFO - TEST-INFO | 1659: exit 0 07:30:36 INFO - TEST-OK | dromaeo_css | took 1119370ms 07:30:36 INFO - TEST-START | kraken 07:30:36 INFO - operating with platform_type : mac_ 07:30:36 INFO - Initialising browser for kraken test... 07:30:36 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp7tVMfG/profile http://localhost:49271/getInfo.html) 07:30:39 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 07:30:39 INFO - PROCESS | 1668 | colorDepth:24 07:30:39 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 07:30:39 INFO - PROCESS | 1668 | __metrics 07:30:39 INFO - PROCESS | 1668 | 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" 07:30:39 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 07:30:39 INFO - TEST-INFO | 1668: exit 0 07:30:39 INFO - Browser initialized. 07:30:39 INFO - Running cycle 1/1 for kraken test... 07:30:39 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/T/tmp7tVMfG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:30:51 INFO - PROCESS | 1669 | RSS: Main: 238780416 07:30:51 INFO - PROCESS | 1669 | 07:30:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 07:30:55 INFO - PROCESS | 1669 | RSS: Main: 289488896 07:30:55 INFO - PROCESS | 1669 | 07:30:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 07:30:58 INFO - PROCESS | 1669 | RSS: Main: 289443840 07:30:58 INFO - PROCESS | 1669 | 07:31:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 07:31:02 INFO - PROCESS | 1669 | RSS: Main: 380780544 07:31:02 INFO - PROCESS | 1669 | 07:31:04 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 07:31:05 INFO - PROCESS | 1669 | RSS: Main: 282140672 07:31:05 INFO - PROCESS | 1669 | 07:31:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:31:08 INFO - PROCESS | 1669 | RSS: Main: 287862784 07:31:08 INFO - PROCESS | 1669 | 07:31:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 07:31:11 INFO - PROCESS | 1669 | RSS: Main: 325341184 07:31:11 INFO - PROCESS | 1669 | 07:31:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 07:31:15 INFO - PROCESS | 1669 | RSS: Main: 326356992 07:31:15 INFO - PROCESS | 1669 | 07:31:17 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 07:31:18 INFO - PROCESS | 1669 | RSS: Main: 324698112 07:31:18 INFO - PROCESS | 1669 | 07:31:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:31:20 INFO - PROCESS | 1669 | RSS: Main: 302637056 07:31:20 INFO - PROCESS | 1669 | 07:31:22 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:31:23 INFO - PROCESS | 1669 | RSS: Main: 303460352 07:31:23 INFO - PROCESS | 1669 | 07:31:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:31:25 INFO - PROCESS | 1669 | RSS: Main: 308682752 07:31:25 INFO - PROCESS | 1669 | 07:31:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:31:29 INFO - PROCESS | 1669 | RSS: Main: 318488576 07:31:29 INFO - PROCESS | 1669 | 07:31:32 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:31:33 INFO - PROCESS | 1669 | RSS: Main: 317083648 07:31:33 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 07:31:35 INFO - PROCESS | 1669 | RSS: Main: 360886272 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | __start_tp_report 07:31:35 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 07:31:35 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 07:31:35 INFO - PROCESS | 1669 | |i|pagename|runs| 07:31:35 INFO - PROCESS | 1669 | |0;ai-astar;113;114;114;115;118;112;112;113;113;112 07:31:35 INFO - PROCESS | 1669 | |1;audio-beat-detection;146;112;112;148;111;132;140;137;141;111 07:31:35 INFO - PROCESS | 1669 | |2;audio-dft;185;187;185;185;185;185;182;172;183;181 07:31:35 INFO - PROCESS | 1669 | |3;audio-fft;79;58;59;86;61;60;59;58;83;57 07:31:35 INFO - PROCESS | 1669 | |4;audio-oscillator;87;73;92;74;69;69;91;68;69;92 07:31:35 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;87;90;105;114;134;83;86;84;88;84 07:31:35 INFO - PROCESS | 1669 | |6;imaging-darkroom;93;91;91;93;92;92;92;93;92;92 07:31:35 INFO - PROCESS | 1669 | |7;imaging-desaturate;84;85;79;81;82;80;84;81;83;80 07:31:35 INFO - PROCESS | 1669 | |8;json-parse-financial;56;54;55;54;55;54;55;54;53;55 07:31:35 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;75;69;70;83;77;76;73;71;69;97 07:31:35 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;88;89;89;90;86;89;86;87;85;85 07:31:35 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;165;158;153;148;146;142;146;146;261;188 07:31:35 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;184;227;203;174;174;175;171;173;173;174 07:31:35 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;67;67;66;68;67;66;66;67;66;66 07:31:35 INFO - PROCESS | 1669 | __end_tp_report 07:31:35 INFO - PROCESS | 1669 | __start_cc_report 07:31:35 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,1983 07:31:35 INFO - PROCESS | 1669 | __end_cc_report 07:31:35 INFO - PROCESS | 1669 | __startTimestamp1478532695363__endTimestamp 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | ------- Summary: start ------- 07:31:35 INFO - PROCESS | 1669 | Number of tests: 14 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:113.60 Median:113.50 stddev:1.84 (1.6%) stddev-sans-first:1.94 07:31:35 INFO - PROCESS | 1669 | Values: 113.0 114.0 114.0 115.0 118.0 112.0 112.0 113.0 113.0 112.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:138.50 stddev:15.68 (11.3%) stddev-sans-first:15.38 07:31:35 INFO - PROCESS | 1669 | Values: 146.0 112.0 112.0 148.0 111.0 132.0 140.0 137.0 141.0 111.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:183.00 Median:185.00 stddev:4.24 (2.3%) stddev-sans-first:4.44 07:31:35 INFO - PROCESS | 1669 | Values: 185.0 187.0 185.0 185.0 185.0 185.0 182.0 172.0 183.0 181.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:66.00 Median:60.50 stddev:11.67 (19.3%) stddev-sans-first:11.39 07:31:35 INFO - PROCESS | 1669 | Values: 79.0 58.0 59.0 86.0 61.0 60.0 59.0 58.0 83.0 57.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:78.40 Median:80.50 stddev:10.67 (13.3%) stddev-sans-first:10.85 07:31:35 INFO - PROCESS | 1669 | Values: 87.0 73.0 92.0 74.0 69.0 69.0 91.0 68.0 69.0 92.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:95.50 Median:85.00 stddev:16.95 (19.9%) stddev-sans-first:17.69 07:31:35 INFO - PROCESS | 1669 | Values: 87.0 90.0 105.0 114.0 134.0 83.0 86.0 84.0 88.0 84.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:92.10 Median:92.00 stddev:0.74 (0.8%) stddev-sans-first:0.71 07:31:35 INFO - PROCESS | 1669 | Values: 93.0 91.0 91.0 93.0 92.0 92.0 92.0 93.0 92.0 92.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:81.90 Median:82.50 stddev:2.02 (2.5%) stddev-sans-first:2.00 07:31:35 INFO - PROCESS | 1669 | Values: 84.0 85.0 79.0 81.0 82.0 80.0 84.0 81.0 83.0 80.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:54.50 Median:55.00 stddev:0.85 (1.5%) stddev-sans-first:0.71 07:31:35 INFO - PROCESS | 1669 | Values: 56.0 54.0 55.0 54.0 55.0 54.0 55.0 54.0 53.0 55.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:76.00 Median:75.50 stddev:8.56 (11.3%) stddev-sans-first:9.08 07:31:35 INFO - PROCESS | 1669 | Values: 75.0 69.0 70.0 83.0 77.0 76.0 73.0 71.0 69.0 97.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:87.40 Median:88.50 stddev:1.84 (2.1%) stddev-sans-first:1.94 07:31:35 INFO - PROCESS | 1669 | Values: 88.0 89.0 89.0 90.0 86.0 89.0 86.0 87.0 85.0 85.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:165.30 Median:155.50 stddev:36.27 (23.3%) stddev-sans-first:38.47 07:31:35 INFO - PROCESS | 1669 | Values: 165.0 158.0 153.0 148.0 146.0 142.0 146.0 146.0 261.0 188.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:182.80 Median:174.50 stddev:18.22 (10.4%) stddev-sans-first:19.32 07:31:35 INFO - PROCESS | 1669 | Values: 184.0 227.0 203.0 174.0 174.0 175.0 171.0 173.0 173.0 174.0 07:31:35 INFO - PROCESS | 1669 | 07:31:35 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.60 Median:67.00 stddev:0.70 (1.0%) stddev-sans-first:0.73 07:31:35 INFO - PROCESS | 1669 | Values: 67.0 67.0 66.0 68.0 67.0 66.0 66.0 67.0 66.0 66.0 07:31:35 INFO - PROCESS | 1669 | -------- Summary: end -------- 07:31:35 INFO - PROCESS | 1669 | 07:31:36 INFO - TEST-INFO | 1669: exit 0 07:31:36 INFO - TEST-OK | kraken | took 59455ms 07:31:36 INFO - SUITE-END | took 1178s 07:31:36 INFO - Completed test suite (00:19:39) 07:31:36 INFO - javascript benchmark 07:31:36 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3334.3164651246493, 3363.327334533094, 3363.882264120755, 3385.9830700846524, 3417.589889213293, 5595.300587426572, 5665.3768085510565, 5712.542996560275, 5769.8269051928455, 5795.82612521624, 4423.447069116361, 4844.249141383091, 5656.066748986423, 5690.5447564194865, 5696.231008813809, 5136.92294615582, 5151.742412879341, 5166.767495462695, 5190.71451070191, 5255.789768409259, 5161.406438259592, 5223.955208958205, 5282.102042652758, 5325.3875804282425, 5341.359036915556, 5313.548348390372, 5318.042752304584, 5328.120860058086, 5360.812371566996, 5392.245085687987, 243.96568713890076, 5300.867478313035, 5305.177697456919, 5311.282976798121, 5378.489043540857, 3208.502181439907, 5159.819406320798, 5222.112240919053, 5229.241759944786, 5234.162533994542, 5660.377358490563, 5695.430456954271, 5700.828975130711, 5727.684977326246, 5730.9426905730825, 6283.497320214372, 6286.151369565142, 6297.118403423525, 6312.368763123697, 6312.463440607554, 6120.294761999619, 6135.3674730672865, 6211.16149319845, 6212.068189771525, 6212.378762123797, 5776.249087618583, 5805.593608447452, 5823.737931793037, 5850.765969361263, 5856.707164641751, 6254.655993920287, 6260.780872669435, 6273.529485288622, 6277.2781130170615, 6279.340669229665, 6016.458518733335, 6078.787242446493, 6080.847978800565, 6178.907316390258, 6183.907241391383, 6182.721777520022, 6208.906866397007, 6248.562600618004, 6266.342034086447, 6298.779542715983, 6195.411435913581, 6209.565330426917, 6213.751449941951, 6242.968785156136, 6247.843803904939, 6122.7550897964475, 6141.670122563672, 6216.968915155395, 6237.594556353823, 6293.937060629427, 4928.605711543068, 4929.309896614477, 4938.876528086755, 4942.382202224722, 4944.159492886218, 4423.093265880487, 4482.148391805546, 4516.841910533181, 4517.548245175456, 4518.277075667943, 5739.741711622987, 5798.333191682926, 5800.187973683687, 5824.88350232993, 5840.503557197686, 6206.565540412218, 6216.036514340284, 6217.782377616762, 6222.160008398899, 6225.750969961151, 5785.82642520725, 5820.476157145878, 5827.79602713903, 5830.504407125357, 5838.241028666331, 5403.270558474555, 5427.294451721251, 5432.049391356497, 5438.945610543923, 5445.809396671177, 6272.5922815016875, 6311.037589189555, 6344.58760180596, 6362.172917520693, 6374.681265936684, 6269.937300626935, 6311.905321420274, 6315.431611155018, 6316.210473690789, 6339.207599050119, 6219.445138715321, 6224.564280500322, 6262.123302737711, 6271.937280627135, 6292.433680968735, 6150.200473938538, 6161.599496032743, 6172.22847144107, 6183.5671502994355, 6199.132121503083, 6186.969065154645, 6265.185525881606, 6280.937190628035, 6323.936760632335, 6331.176946996861, 5971.104334349882, 6051.122587224916, 6059.21230240066, 6137.539684523768, 6145.0782382642965, 5981.491573216325, 6083.756649733961, 6090.9390906090375, 6099.969500152648, 6151.800398922346, 5999.430054144849, 5999.790007349722, 6002.0, 6017.217761690952, 6025.487833534024, 5993.9700301498215, 6050.757969681164, 6147.047207682816, 6153.6923153841235, 6168.814935551941, 5838.241028666246, 5919.970400147971, 5926.970365148147, 5936.673482958478, 5947.524198064231], "value": 5664.527842670883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [508.80919655129327, 514.6963291657921, 519.2470917170104, 524.0828550037435, 529.4705294705295, 29804.850975745117, 30347.0, 31485.842570787143, 38898.8055059725, 39980.60019399812, 40855.59144408555, 41125.7943710281, 41141.177176456455, 41209.0, 41266.0, 9386.061393860628, 9540.713778586654, 9560.61757529698, 9575.281853860954, 9587.185089267421, 8567.828643427129, 8742.650293988232, 9313.394629349104, 9334.626614935429, 11281.0, 11122.277051991636, 11198.38408887511, 11225.60710375137, 11252.774944501105, 11270.549178032868, 11342.716432089223, 11397.943010284895, 11408.771824563546, 11410.71473213168, 11410.942945285262, 8455.74632761012, 8702.695405660836, 10990.0, 11251.943740281327, 11293.20947533673, 38323.23353532942, 38892.805535972424, 38945.805270973746, 38946.0, 38985.0, 3285.704286614216, 3383.4924761285756, 3423.3666771647327, 3542.079059444537, 3662.2675464907124, 29997.400051998837, 30195.396092078256, 30212.54681179784, 30298.0, 30378.240543986358, 3097.6902309769066, 3136.702013308732, 3291.1772056985756, 3329.6004479462376, 3336.0, 459116.0, 459170.0, 460225.0, 460305.0, 477135.61432192964, 54813.451865481635, 55137.89724205493, 55190.0, 55400.0, 55476.44523554794, 57532.13701794476, 57574.42425575691, 57584.712076440184, 57620.847583048104, 57645.0, 12604.0, 15522.844771552365, 15527.301271442811, 15534.378624854879, 15539.84460155384, 12158.635240942785, 12195.207311524722, 12202.14584979043, 12213.511459541696, 12216.450259738156, 13156.473741050251, 13451.730965380637, 14507.709845803025, 14932.626684332761, 14987.775183372258, 6456.838579035561, 6697.933020669829, 6916.619585922823, 7059.71761129549, 7147.8570428592175, 794.3049831397527, 910.717677519971, 930.7626555228373, 965.8696076029646, 1019.7144799456164, 28648.14055578336, 28838.0, 29234.707652923626, 29415.705842941723, 29554.0, 933.2767105493297, 1019.7246743379246, 1020.1277907389075, 1092.5575142067476, 1119.160629527854, 14595.270236488133, 14939.0, 15047.172405517582, 15315.927885048162, 15352.622423066907, 625383.0, 628461.0, 630584.0, 631415.8429207916, 634570.0, 47640.80897977509, 48187.759061204466, 48366.0, 53819.46180538145, 54867.45132548703, 1280.7886698694797, 1322.735452909422, 1328.4686125549858, 1331.800229965513, 1339.8660133986914, 29768.10695679133, 29803.552946705382, 30222.217820997594, 30285.545716814708, 30383.088507344815, 47273.0, 47794.28308575444, 47938.0, 48045.47477040185, 48057.0, 537101.0, 617062.9146854237, 620189.0, 635434.0, 781462.0, 21371.46571335729, 22656.66015009809, 24274.02903883825, 24659.753402465747, 24755.504889901742, 43621.781891090344, 43942.34086488641, 44590.55409445864, 44764.32853507135, 44986.55013449823, 36813.50271724182, 37360.0, 38311.0, 38666.6133338663, 40053.599464006154, 36883.0, 37089.25821483501, 37555.2488950214, 38318.42522362111, 38426.23147537089, 36368.52455751683, 37192.69825556093, 37280.254394911404, 37391.81304093462, 37657.24685506329, 32350.838245809562, 33095.84164554229, 33782.83108584442, 33786.3242735139, 33811.492827607115, 24586.631200531647, 24812.50374992454, 24874.378140546632, 24975.87512062428, 25167.0], "value": 19380.218620323994, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.52699065775226, 364.6827260283554, 367.6690978119694, 375.48933450507315, 396.2174704957707, 14297.714045719093, 14827.555173344803, 15548.06711597305, 19573.706394404096, 20145.899270503625, 18544.814551854477, 19192.9040354798, 19526.414207573795, 19754.814711117306, 19790.70313945292, 5372.516473517392, 6000.969995150018, 6009.4891934185425, 6015.909761353583, 6018.6689732064715, 5604.915926261089, 5823.737931793058, 5856.853578660546, 5896.911546326808, 5900.085486749561, 5735.713214339266, 5790.7104644767805, 5871.0, 5877.52979761618, 5879.706014699269, 5524.33707955046, 5758.654480731169, 5812.099124635688, 5821.0, 5824.184614154022, 4824.951750482485, 5044.344235249431, 5053.570446512014, 5055.545000949895, 5073.213651883964, 23624.0, 23869.522609547887, 24057.639135412985, 24214.757852421426, 24344.02623895042, 2243.5176437065948, 2383.9404014899765, 2451.509698060377, 2624.8818803153904, 2730.918107239206, 14662.340194691264, 15335.079895206323, 15378.0, 15504.612384690474, 15611.985220960714, 1760.4278609451978, 1770.2741875830977, 1817.6546456173417, 1828.882388410764, 1966.9704954425695, 104725.47637261842, 120070.3996480012, 120437.39781301125, 123609.38195309056, 125405.74594254032, 8736.606852691644, 10065.547050382751, 10420.218483613759, 10434.739131521621, 10447.477626118663, 12705.17416367933, 12745.0, 12934.870651293366, 12944.0, 12974.469978354131, 8103.756887293391, 8243.917560824435, 8254.8218935709, 8296.0, 8364.289035432057, 6964.756233531802, 8302.501849889026, 8312.70905518316, 8503.61733721984, 8507.489550626982, 5439.347278326626, 5918.934591773522, 6022.488088512438, 6172.382761723881, 6184.103305020747, 1795.6857549928711, 1802.9549261268312, 1813.4740925131828, 1819.9909000454913, 1851.8425933795509, 831.7754206364368, 870.8693695945532, 923.3472084967966, 975.6341371985505, 1060.8143574874366, 19218.615627687366, 20046.997650117435, 20396.0, 20917.477063073242, 21351.0, 840.3865178419666, 885.526243459746, 889.7001251764133, 943.5423819447562, 944.6740874398322, 9293.395929264576, 10260.948695256626, 10635.202359823044, 10672.359658420519, 10713.928607139223, 588696.0, 597799.0, 600661.0, 619140.0, 622597.0, 66044.0, 81598.36803263902, 106192.0, 130336.69663303245, 131326.0, 928.6749637626776, 968.7771812483169, 1006.9697909062739, 1009.8182327181178, 1010.2625083688948, 17957.461276161735, 18357.632847343244, 18401.0, 18464.630707386044, 18466.81533184651, 34232.657673422946, 54290.728546357015, 54803.903921920544, 56690.0, 56973.0, 76744.84882726875, 79751.60124199341, 85266.1473385283, 86586.56706716426, 88223.0, 7424.836861967132, 7536.434767392577, 7757.340626046849, 7825.726099586487, 7922.960385198037, 10680.83978740304, 11586.942065289619, 11756.11829112837, 12359.62921112368, 12428.378581070729, 9408.200302974325, 10226.539805708611, 10458.0, 10905.672829814801, 10989.945050274697, 9561.709169262338, 9889.258305626963, 10483.633072842415, 10691.679249622524, 10862.782744344911, 10441.477926103817, 10599.099076578268, 11071.05895998817, 11124.666260012213, 11213.943930280297, 7608.353289970193, 7693.846123077619, 7737.767866964, 7813.648385822538, 8033.959830200812, 9443.811123777623, 10563.419011954416, 10595.311304765166, 10867.13062954946, 10928.289661172317], "value": 9367.264288394483, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1473.0646039764747, 1475.8819294456441, 1668.2826384654593, 1745.9388921387754, 1848.64875673622, 7766.262205090515, 8212.83574328513, 8216.465929714564, 8229.300509456696, 8244.587770611475, 7228.4217262618995, 7523.172451030382, 7544.660490277937, 7600.20197879223, 7610.16288208298, 1945.8540609454349, 2051.107768120865, 2089.3836318286067, 2168.392850002002, 2275.931722048333, 2269.217120093565, 2308.2613563659634, 2309.9538009239805, 2311.3759284993125, 2317.79139877411, 1927.633749587574, 2219.3009202101366, 2288.347820871052, 2298.2415802785126, 2302.8157747380255, 2254.5265494246146, 2259.6949411829355, 2274.283600665793, 2275.112706044644, 2288.313505948209, 2287.1880482428664, 2310.318456055468, 2312.629979203319, 2317.3279748872806, 2323.953520929572, 7543.717568013451, 7774.805629859242, 8103.5948202590225, 8122.75631731049, 8195.34437245019, 4932.8273510427325, 5099.311592934943, 5102.0, 5102.081625307444, 5104.3619547556555, 3094.932248374307, 3165.4618714818307, 3406.097384193191, 3430.6740859618294, 3494.091536200581, 859.5143743784741, 1008.7478130467383, 1042.405828677654, 1061.5753698520575, 1123.690984979129, 10266.845997310047, 10427.947860260727, 10503.999999999924, 10546.419946902917, 10553.261271710984, 4250.404943307939, 4340.8697739067875, 4507.414036175342, 4514.796834142471, 4554.931676024862, 9167.587458564381, 9175.816483670424, 9178.586963586655, 9185.127412895765, 9194.724158275263, 5078.415982162025, 5086.618503612244, 5092.6180536459915, 5094.643374963717, 5096.719680417538, 4468.754218518013, 4579.931301030487, 4580.55712450577, 4609.562091601292, 4681.087187998308, 3080.4609193391516, 3512.3853325667906, 3636.654517820803, 3654.104744337654, 3795.525559305087, 1037.7094413564214, 1235.3144005077268, 1235.6601934467913, 1247.7317376763858, 1248.8001919692806, 848.117957324388, 862.4221771413169, 867.2931560777946, 913.59345091435, 919.2691810010923, 5528.917066244009, 5645.943540564542, 5787.942120578825, 5831.241938547962, 5991.191189189403, 880.7577916073132, 893.860328081701, 908.3777612335572, 915.4370062411568, 956.7942892278194, 3390.965755444613, 3485.634008429129, 3747.5877653458097, 3841.654251117413, 3914.412838074312, 9253.444793312423, 9457.243420526342, 9644.03559644044, 9802.166815820592, 9820.901790982141, 8530.976282845973, 9522.14300712939, 9561.378510396802, 9741.220702343795, 9789.510524473748, 7465.253474652492, 7586.468947173756, 7656.464047516621, 7668.271514206142, 7693.615319234016, 5389.164679474688, 5510.173473978935, 5515.751791169327, 5517.806876759294, 5593.021221286177, 8977.551122443982, 9026.413283136837, 9026.45841249527, 9146.58840352199, 9252.49111298885, 9136.6802161924, 9212.216961558343, 9270.675526356545, 9323.20752736025, 9359.485228312507, 6941.791746247621, 7069.787906362817, 7081.964590177016, 7082.362587367162, 7110.893336599852, 7385.409167266714, 7596.392288616787, 7680.615969201406, 7684.077910650758, 7700.191479894755, 6593.637349945799, 6682.198136223684, 6874.415674667509, 6881.554873476426, 7038.929610704033, 6578.177727784027, 6591.736330546724, 6744.0895479109695, 6770.390664840188, 6849.726010959505, 6481.5138864584405, 6545.607263564201, 6566.770163044271, 6567.9671601641685, 6570.244421891543, 4607.562281583244, 4759.76201189946, 4788.16207163738, 4803.159447096674, 4868.026394721141, 4653.650976176868, 4743.525647435229, 4840.419149702058, 4875.0, 4984.352034235526], "value": 4302.128326437266, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.6499530553946, 297.3369386268621, 307.1246946203321, 310.83215063865515, 310.9751219902408, 5047.343845300106, 5248.685078895269, 5282.860441521185, 5296.569926119945, 5315.325672413187, 3867.883963481093, 4537.659675524341, 5143.3570803649545, 5152.69083854969, 5157.638965272433, 3899.512560929884, 4013.5183777946686, 4026.932862791363, 4027.5166979962446, 4033.479075577764, 3252.2682396460846, 4004.1591265834063, 4009.3985902114623, 4018.5981401859726, 4028.415879697442, 3817.950063732468, 3995.2808494470983, 3998.3002974479496, 4017.3973903914352, 4047.7571345719202, 3473.8089405082965, 3536.9292614147703, 3970.54338751043, 3997.4023378958877, 4018.9196216075807, 2749.766269867064, 2899.5215789394724, 3469.259285164717, 3482.8258587070545, 3484.529588505545, 4601.056783359403, 4857.467624328029, 5152.742362881841, 5156.303898973628, 5188.273641690166, 4177.248095342868, 4399.724080016791, 4562.452505699337, 4564.771761411949, 4569.8750081212065, 5549.278593782823, 5557.777688892439, 5569.805056822992, 5571.997040532702, 5581.325602319329, 3125.828079455629, 3264.9673503264903, 3369.275605744777, 3787.9621203787883, 3810.371288737355, 4364.192624364471, 5072.467390923936, 5108.923366149511, 5295.4969277918535, 5676.432356764331, 3641.1807343348064, 3843.7501562398356, 3845.3655146901006, 3849.653531182207, 3856.305864944281, 3678.2643471305846, 3903.828851344642, 3906.6679332257077, 3915.353966595537, 3920.921581568353, 4015.598440155961, 4095.9180816383505, 4388.890277743082, 4510.797014134372, 4541.795619197144, 5171.3018742469285, 5570.136628822539, 5574.777008919597, 5593.636963168735, 5624.831255062355, 1083.766990097133, 1085.7557049663762, 1202.212550779241, 1208.3897631695938, 1208.9214201076902, 841.9036598868772, 854.5513605357113, 857.8241460500583, 862.5810541658936, 863.9481631102154, 4527.796249168795, 4561.521040290721, 4928.654994150375, 4937.481564435754, 4960.255961605788, 5165.354330708661, 5317.931782186553, 5368.7047212403695, 5374.3013408256675, 5416.214648875891, 4086.0, 4197.8320867165585, 4893.290472881484, 4903.975480122576, 4918.655694101379, 2509.4228327484416, 2786.0, 2804.775617950559, 2821.2946763309174, 2826.321682796114, 5193.780250847949, 5446.809361672323, 5465.316835395575, 5530.768961744269, 5606.608002158717, 4893.568139365301, 5216.739163041909, 5483.396826349099, 5486.0125177468435, 5503.889922201533, 4296.014270082325, 4367.781610919441, 4983.8504844854715, 5057.8735531612, 5633.450925916728, 4445.199864024377, 4662.110483333771, 4993.579398112304, 5113.897722045613, 5353.063213937623, 4915.5330243626795, 5270.920936185884, 5273.574155888181, 5293.338332708411, 5316.680999140064, 4482.775861206992, 5521.4478552144465, 5542.697466095384, 5548.916766248591, 5586.748596313095, 3078.7383072438197, 3209.5185722141864, 3588.474898168216, 3595.17311018462, 3615.457681347819, 4440.422745043253, 5100.719460429712, 5269.2348063399195, 5301.575873929999, 5352.571794256372, 5170.948290517197, 5187.221916712372, 5200.60995425337, 5218.9739051304505, 5229.9801538699585, 4617.353570500066, 4735.437305689184, 4889.315495830516, 4942.901141977069, 4993.550580447777, 4328.718633288826, 4368.890777730582, 4572.588467037983, 4941.616347422728, 4980.576650984707, 4962.751862406937, 5032.943081952831, 5329.9467005331, 5497.120460726264, 5504.4770746778995, 5450.509454149145, 5467.5625949923115, 5478.685115572313, 5498.752556135064, 5509.201165830852], "value": 3880.592851899504, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [715.9511315922176, 716.0905649824723, 757.9052618422697, 782.0360832445034, 792.845395147946, 14976.92511537442, 15217.695646087099, 15244.237788110606, 15254.694906101871, 15269.0, 13559.983001274895, 13688.17870927745, 13707.45170193191, 13723.245221512812, 13726.794098088536, 659.8944168932971, 673.7574473189648, 676.3777324861139, 806.7176488229117, 831.584207896052, 819.7171975668385, 825.9628316725732, 833.8457385383693, 835.5947365527715, 835.9205875441824, 885.3183049052258, 885.4067774591007, 886.2909672262165, 888.3071204460531, 888.564603344363, 691.837418206721, 711.8006958051753, 876.4040452492303, 876.4434584039149, 877.6533269358631, 629.6914511889187, 648.3160265919453, 649.7758273395671, 650.1840190560863, 652.3834975947731, 13486.12340197884, 13497.595072147851, 13511.594652160451, 13535.796963045563, 13539.864601354057, 13157.671058223525, 13331.933340333333, 13471.191728496322, 13471.854892334164, 13494.190348579117, 9888.47360741907, 12210.57262995791, 12277.508899644003, 12294.446749896186, 12395.504179832797, 4331.675124365685, 4369.0, 4397.956020439787, 4560.8859778505475, 4577.107464044512, 88537.55731221367, 90211.0, 93328.06671933262, 93590.12819743567, 94973.0, 10504.474776261157, 10564.049235568837, 10585.259031867696, 10613.310134841211, 10951.34291942486, 18462.0, 19276.0, 20625.896870515553, 20690.068946897427, 20739.58520829575, 7614.619269036526, 7769.53382797034, 7863.444380576336, 7867.685292588232, 7992.720254791171, 4345.239583073013, 4367.432233809584, 4406.25093734066, 4444.333349997526, 4469.821207151743, 3393.5927688677516, 3502.649735026477, 3511.2626348467106, 3511.3328467591077, 3511.894643160709, 651.3811878715239, 651.5341530805497, 658.1674182159572, 659.5416185750953, 662.8276648071536, 513.7208351205546, 655.8163714160042, 666.5767237803932, 670.6311528659218, 675.5136301862651, 7404.296591823768, 8896.8665470018, 9145.451272923647, 9227.123423274777, 9303.860442093373, 635.2408871398727, 645.4868379638199, 666.9599824010478, 667.6761770541284, 669.2270427656065, 4095.959040409558, 4511.639068874482, 4516.187086324493, 4551.294549344857, 4614.492405835355, 94462.52768736113, 112036.0, 113319.43340283411, 114391.42804285925, 125103.12345314828, 39901.60098399037, 63145.368546314865, 63278.101656949446, 78409.60795195987, 85074.0, 56881.43118568844, 76554.61722691351, 82705.93235169169, 83298.58350708208, 84293.0, 8484.703035393732, 9523.857142142735, 9568.712938611852, 9622.663206787729, 9648.855267170858, 52399.0, 52660.73669631627, 54179.0, 54478.910421792134, 55846.16230756623, 62754.372456274854, 76580.0, 77531.61234194018, 77713.0, 79368.60315698385, 11954.521819127485, 12307.938460307641, 12351.69120771988, 12394.380280985733, 12703.0, 13717.314134293445, 13776.0, 14009.159450433006, 14013.43946242139, 14317.069390489585, 12872.806907896576, 12881.935590321988, 13274.734505310033, 13563.829787233995, 13713.45146194181, 13009.0, 13018.414171362501, 13030.153040052366, 13065.542706005244, 13554.118982266122, 12657.066038818313, 13106.868931310564, 13140.60578182656, 13157.802632960706, 13419.928420448157, 11152.721181970515, 11162.720931976766, 11184.776304474028, 11309.193305306275, 11600.477978490819, 9589.369807132665, 10161.898381016095, 10724.8927510727, 11120.944395277971, 11233.01620261755], "value": 7276.208618402718, "unit": "score"}], "name": "dromaeo_css", "value": 7070.4197285968185, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 114.0, 114.0, 115.0, 118.0, 112.0, 112.0, 113.0, 113.0, 112.0], "value": 113.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 112.0, 148.0, 111.0, 132.0, 140.0, 137.0, 141.0, 111.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 187.0, 185.0, 185.0, 185.0, 185.0, 182.0, 172.0, 183.0, 181.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [79.0, 58.0, 59.0, 86.0, 61.0, 60.0, 59.0, 58.0, 83.0, 57.0], "value": 66.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 73.0, 92.0, 74.0, 69.0, 69.0, 91.0, 68.0, 69.0, 92.0], "value": 78.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 90.0, 105.0, 114.0, 134.0, 83.0, 86.0, 84.0, 88.0, 84.0], "value": 95.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 91.0, 91.0, 93.0, 92.0, 92.0, 92.0, 93.0, 92.0, 92.0], "value": 92.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 79.0, 81.0, 82.0, 80.0, 84.0, 81.0, 83.0, 80.0], "value": 81.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 55.0, 54.0, 55.0, 54.0, 55.0, 54.0, 53.0, 55.0], "value": 54.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 69.0, 70.0, 83.0, 77.0, 76.0, 73.0, 71.0, 69.0, 97.0], "value": 76.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 89.0, 89.0, 90.0, 86.0, 89.0, 86.0, 87.0, 85.0, 85.0], "value": 87.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [165.0, 158.0, 153.0, 148.0, 146.0, 142.0, 146.0, 146.0, 261.0, 188.0], "value": 165.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 227.0, 203.0, 174.0, 174.0, 175.0, 171.0, 173.0, 173.0, 174.0], "value": 182.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 68.0, 67.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.6, "unit": "score"}], "name": "kraken", "value": 1472.1, "alertThreshold": 2.0}]} 07:31:36 INFO - Return code: 0 07:31:36 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:31:36 INFO - # TBPL SUCCESS # 07:31:36 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:31:36 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:31:36 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:31:36 INFO - 2016-11-07 07:31:36.729 screenresolution[1673:11059] starting screenresolution argv=screenresolution get 07:31:36 INFO - 2016-11-07 07:31:36.732 screenresolution[1673:11059] Display 0: 1600x1200x32@60 07:31:36 INFO - 2016-11-07 07:31:36.738 screenresolution[1674:11062] starting screenresolution argv=screenresolution list 07:31:36 INFO - Available Modes on Display 0 07:31:36 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:31:36 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:31:36 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:31:36 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:31:36 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:31:36 INFO - Intel Iris: 07:31:36 INFO - Chipset Model: Intel Iris 07:31:36 INFO - Type: GPU 07:31:36 INFO - Bus: Built-In 07:31:36 INFO - VRAM (Dynamic, Max): 1536 MB 07:31:36 INFO - Vendor: Intel (0x8086) 07:31:36 INFO - Device ID: 0x0a2e 07:31:36 INFO - Revision ID: 0x0009 07:31:36 INFO - Displays: 07:31:36 INFO - Display: 07:31:36 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:31:36 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:31:36 INFO - Display Serial Number: 1600x1200 60 07:31:36 INFO - Main Display: Yes 07:31:36 INFO - Mirror: Off 07:31:36 INFO - Online: Yes 07:31:36 INFO - Rotation: Supported 07:31:36 INFO - Return code: 0 07:31:36 INFO - Running post-action listener: _resource_record_post_action 07:31:36 INFO - [mozharness: 2016-11-07 15:31:36.973707Z] Finished run-tests step (success) 07:31:36 INFO - Running post-run listener: _resource_record_post_run 07:31:37 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 33299456; Write bytes: 710514176; Read time: 316; Write time: 1910 07:31:37 INFO - TinderboxPrint: CPU usage
53.7% 07:31:37 INFO - TinderboxPrint: I/O read bytes / time
33,299,456 / 316 07:31:37 INFO - TinderboxPrint: I/O write bytes / time
710,514,176 / 1,910 07:31:37 INFO - TinderboxPrint: CPU idle
2,247.1 (46.3%) 07:31:37 INFO - TinderboxPrint: CPU system
970.7 (20.0%) 07:31:37 INFO - TinderboxPrint: CPU user
1,639.8 (33.8%) 07:31:37 INFO - TinderboxPrint: Swap in / out
447,328,256 / 0 07:31:37 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398868992; Write bytes: 393254400; Read time: 29598; Write time: 697 07:31:37 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 31214592; Write bytes: 298818048; Read time: 293; Write time: 1112 07:31:37 INFO - Running post-run listener: _upload_blobber_files 07:31:37 INFO - Blob upload gear active. 07:31:37 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:31:37 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 07:31:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Cedar', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:31:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Cedar -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:31:37 INFO - (blobuploader) - INFO - Open directory for files ... 07:31:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 07:31:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:38 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 07:31:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:38 INFO - (blobuploader) - INFO - Done attempting. 07:31:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 07:31:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 07:31:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:39 INFO - (blobuploader) - INFO - Done attempting. 07:31:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:31:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:31:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:31:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:31:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:31:41 INFO - (blobuploader) - INFO - Done attempting. 07:31:41 INFO - (blobuploader) - INFO - Iteration through files over. 07:31:41 INFO - Return code: 0 07:31:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:31:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:31:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f0d9f2ee529ad7fd687619deafd953dde7c19f63f6b258fb2aa391c1dc0faca53d353f4c89c4761f3a53cae70efbbf61cebcd1748f73980e201f4d00f3ea7fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f13490dea0c69ddc773458362887a812ea3dce95da6f034373f8365d9f5f2a737a9d7efd74c5a21a7deb68674ab2aa119d760fc1363f2c6e3dba46959abd9c81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c7ffbb022ac396957cd2ceb8b16c45128e5bf84a0d3ace4c97c26372f39274335cce0985d14d1f62e6055edd73ada31614076fce5fb8b857434107a85d615b3d"} 07:31:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:31:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:31:41 INFO - Contents: 07:31:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f0d9f2ee529ad7fd687619deafd953dde7c19f63f6b258fb2aa391c1dc0faca53d353f4c89c4761f3a53cae70efbbf61cebcd1748f73980e201f4d00f3ea7fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f13490dea0c69ddc773458362887a812ea3dce95da6f034373f8365d9f5f2a737a9d7efd74c5a21a7deb68674ab2aa119d760fc1363f2c6e3dba46959abd9c81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c7ffbb022ac396957cd2ceb8b16c45128e5bf84a0d3ace4c97c26372f39274335cce0985d14d1f62e6055edd73ada31614076fce5fb8b857434107a85d615b3d"} 07:31:41 INFO - Running post-run listener: copy_logs_to_upload_dir 07:31:41 INFO - Copying logs to upload dir... 07:31:41 INFO - mkdir: /builds/slave/test/build/upload/logs 07:31:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.906994 ========= master_lag: 3.50 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-11-07 07:31:45.210525) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:45.215355) ========= 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.vgedz3FKd3/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.2DOdEIFZrR/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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/Cedar/sha512/f0d9f2ee529ad7fd687619deafd953dde7c19f63f6b258fb2aa391c1dc0faca53d353f4c89c4761f3a53cae70efbbf61cebcd1748f73980e201f4d00f3ea7fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f13490dea0c69ddc773458362887a812ea3dce95da6f034373f8365d9f5f2a737a9d7efd74c5a21a7deb68674ab2aa119d760fc1363f2c6e3dba46959abd9c81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c7ffbb022ac396957cd2ceb8b16c45128e5bf84a0d3ace4c97c26372f39274335cce0985d14d1f62e6055edd73ada31614076fce5fb8b857434107a85d615b3d"} build_url:https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011027 build_url: 'https://queue.taskcluster.net/v1/task/JFPqSZY8Trea5s7436XBkQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f0d9f2ee529ad7fd687619deafd953dde7c19f63f6b258fb2aa391c1dc0faca53d353f4c89c4761f3a53cae70efbbf61cebcd1748f73980e201f4d00f3ea7fb9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/f13490dea0c69ddc773458362887a812ea3dce95da6f034373f8365d9f5f2a737a9d7efd74c5a21a7deb68674ab2aa119d760fc1363f2c6e3dba46959abd9c81", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Cedar/sha512/c7ffbb022ac396957cd2ceb8b16c45128e5bf84a0d3ace4c97c26372f39274335cce0985d14d1f62e6055edd73ada31614076fce5fb8b857434107a85d615b3d"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:45.256084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:45.256379) ========= 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.vgedz3FKd3/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.2DOdEIFZrR/Listeners TMPDIR=/var/folders/d1/6kk031nx1xl7zwdbm4mjf_yc00000w/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.005096 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-11-07 07:31:45.308522) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-07 07:31:45.308797) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-11-07 07:31:45.309081) ========= ========= Total master_lag: 3.78 =========