builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0253 starttime: 1484338648.26 results: success (0) buildid: 20170113111023 builduid: 5fd3d95f81fa476996c8fe7202b8d31c revision: de66a249a38b540ea34e9eb36a8d6a72c9fde4ef ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.263297) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.264332) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.265101) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.292717) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.293459) ========= 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.mW8kHx2fh9/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.gD5uafFP7K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-13 12:17:28-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 161M=0s 2017-01-13 12:17:28 (161 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.254826 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.578612) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.579372) ========= 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.mW8kHx2fh9/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.gD5uafFP7K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.024744 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:28.631745) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 12:17:28.632431) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev de66a249a38b540ea34e9eb36a8d6a72c9fde4ef --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev de66a249a38b540ea34e9eb36a8d6a72c9fde4ef --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mW8kHx2fh9/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.gD5uafFP7K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-13 12:17:28,731 truncating revision to first 12 chars 2017-01-13 12:17:28,731 Setting DEBUG logging. 2017-01-13 12:17:28,731 attempt 1/10 2017-01-13 12:17:28,731 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/de66a249a38b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-13 12:17:29,807 unpacking tar archive at: mozilla-aurora-de66a249a38b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.345427 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-13 12:17:30.005247) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:30.005699) ========= script_repo_revision: de66a249a38b540ea34e9eb36a8d6a72c9fde4ef ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:30.006248) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:30.006621) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-13 12:17:30.027516) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-13 12:17:30.028076) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 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', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.mW8kHx2fh9/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.gD5uafFP7K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 12:17:30 INFO - MultiFileLogger online at 20170113 12:17:30 in /builds/slave/test 12:17:30 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 12:17:30 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:17:30 INFO - {'append_to_log': False, 12:17:30 INFO - 'base_work_dir': '/builds/slave/test', 12:17:30 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 12:17:30 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:17:30 INFO - 'branch': 'Mozilla-Aurora', 12:17:30 INFO - 'buildbot_json_path': 'buildprops.json', 12:17:30 INFO - 'config_files': ('talos/mac_config.py',), 12:17:30 INFO - 'default_actions': ('clobber', 12:17:30 INFO - 'read-buildbot-config', 12:17:30 INFO - 'download-and-extract', 12:17:30 INFO - 'populate-webroot', 12:17:30 INFO - 'create-virtualenv', 12:17:30 INFO - 'install', 12:17:30 INFO - 'run-tests'), 12:17:30 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:17:30 INFO - 'download_minidump_stackwalk': True, 12:17:30 INFO - 'download_symbols': 'ondemand', 12:17:30 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:17:30 INFO - 'tooltool.py': '/tools/tooltool.py', 12:17:30 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:17:30 INFO - '/tools/misc-python/virtualenv.py')}, 12:17:30 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:17:30 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:17:30 INFO - 'installer_path': 'installer.exe', 12:17:30 INFO - 'log_level': 'info', 12:17:30 INFO - 'log_name': 'talos', 12:17:30 INFO - 'log_to_console': True, 12:17:30 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:17:30 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:17:30 INFO - 'opt_config_files': (), 12:17:30 INFO - 'pip_index': False, 12:17:30 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:30 INFO - 'cmd': ('bash', 12:17:30 INFO - '-c', 12:17:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:30 INFO - 'enabled': True, 12:17:30 INFO - 'halt_on_failure': False, 12:17:30 INFO - 'name': 'check_screen_resolution'},), 12:17:30 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:17:30 INFO - 'cmd': ('bash', 12:17:30 INFO - '-c', 12:17:30 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:17:30 INFO - 'enabled': True, 12:17:30 INFO - 'halt_on_failure': False, 12:17:30 INFO - 'name': 'check_screen_resolution'},), 12:17:30 INFO - 'run_cmd_checks_enabled': True, 12:17:30 INFO - 'sps_profile': False, 12:17:30 INFO - 'sps_profile_interval': 0, 12:17:30 INFO - 'suite': 'dromaeojs', 12:17:30 INFO - 'system_bits': '32', 12:17:30 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:17:30 INFO - 'title': 't-yosemite-r7-0253', 12:17:30 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:17:30 INFO - 'use_talos_json': True, 12:17:30 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:17:30 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:17:30 INFO - 'work_dir': 'build'} 12:17:30 INFO - [mozharness: 2017-01-13 20:17:30.233406Z] Running clobber step. 12:17:30 INFO - Running pre-action listener: _resource_record_pre_action 12:17:30 INFO - Running main action method: clobber 12:17:30 INFO - rmtree: /builds/slave/test/build 12:17:30 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:17:32 INFO - Running post-action listener: _resource_record_post_action 12:17:32 INFO - [mozharness: 2017-01-13 20:17:32.557834Z] Finished clobber step (success) 12:17:32 INFO - [mozharness: 2017-01-13 20:17:32.557989Z] Running read-buildbot-config step. 12:17:32 INFO - Running pre-action listener: _resource_record_pre_action 12:17:32 INFO - Running main action method: read_buildbot_config 12:17:32 INFO - Using buildbot properties: 12:17:32 INFO - { 12:17:32 INFO - "project": "", 12:17:32 INFO - "product": "firefox", 12:17:32 INFO - "script_repo_revision": "production", 12:17:32 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 12:17:32 INFO - "repository": "", 12:17:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 12:17:32 INFO - "buildid": "20170113111023", 12:17:32 INFO - "pgo_build": "False", 12:17:32 INFO - "basedir": "/builds/slave/test", 12:17:32 INFO - "buildnumber": 75, 12:17:32 INFO - "slavename": "t-yosemite-r7-0253", 12:17:32 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 12:17:32 INFO - "platform": "yosemite_r7", 12:17:32 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 12:17:32 INFO - "branch": "mozilla-aurora", 12:17:32 INFO - "repo_path": "releases/mozilla-aurora", 12:17:32 INFO - "revision": "de66a249a38b540ea34e9eb36a8d6a72c9fde4ef", 12:17:32 INFO - "stage_platform": "macosx64", 12:17:32 INFO - "builduid": "5fd3d95f81fa476996c8fe7202b8d31c", 12:17:32 INFO - "slavebuilddir": "test" 12:17:32 INFO - } 12:17:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 12:17:32 INFO - Running post-action listener: _resource_record_post_action 12:17:32 INFO - [mozharness: 2017-01-13 20:17:32.561505Z] Finished read-buildbot-config step (success) 12:17:32 INFO - [mozharness: 2017-01-13 20:17:32.561651Z] Running download-and-extract step. 12:17:32 INFO - Running pre-action listener: _resource_record_pre_action 12:17:32 INFO - Running main action method: download_and_extract 12:17:32 INFO - mkdir: /builds/slave/test/build/tests 12:17:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:32 INFO - trying https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 12:17:32 INFO - Downloading https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:17:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 12:17:33 INFO - Downloaded 1199 bytes. 12:17:33 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 12:17:33 INFO - Using the following test package requirements: 12:17:33 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:17:33 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 12:17:33 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 12:17:33 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'jsshell-mac.zip'], 12:17:33 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 12:17:33 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 12:17:33 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 12:17:33 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 12:17:33 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 12:17:33 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 12:17:33 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 12:17:33 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 12:17:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:17:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:17:33 INFO - Fetch https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:17:34 INFO - Http code: 200 12:17:34 INFO - via: 1.1 ba26a6eef2785fa2e20e601890b882b2.cloudfront.net (CloudFront) 12:17:34 INFO - x-amz-cf-id: DAyE1ErktU17_PL33PQQ1l2dcapMbBGC27Q2ES3juvRF9UId5soluA== 12:17:34 INFO - x-amz-version-id: vjRGBIDi0gmmMkqUrbXRQFfmlfUql5bD 12:17:34 INFO - Expected file size: 16275680 12:17:34 INFO - Obtained file size: 16275680 12:17:41 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 12:17:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 12:17:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 12:17:41 INFO - Fetch https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 12:17:42 INFO - Http code: 200 12:17:42 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 12:17:42 INFO - x-amz-cf-id: 6bEQESq7zfpawF6zUj2bRZmx5sk9jfeXQI2TcZZuo4pJZH2oidW2JQ== 12:17:42 INFO - x-amz-version-id: vjRGBIDi0gmmMkqUrbXRQFfmlfUql5bD 12:17:42 INFO - Expected file size: 16275680 12:17:42 INFO - Obtained file size: 16275680 12:17:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 12:17:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 12:17:48 INFO - Fetch https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 12:17:49 INFO - Http code: 200 12:17:49 INFO - via: 1.1 66ed69e8b47ad05050331602c798132f.cloudfront.net (CloudFront) 12:17:49 INFO - x-amz-cf-id: oz8aDJZHRAbr3PC5V5a1EBi75pJQckIMizh4nTGPKw2_u8sC3xgqxA== 12:17:49 INFO - x-amz-version-id: eu_TL4u6kZ4BmEJCio84Slb8huMMUpvy 12:17:49 INFO - Expected file size: 14409038 12:17:49 INFO - Obtained file size: 14409038 12:17:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:49 INFO - trying https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:17:49 INFO - Downloading https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 12:17:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 12:17:52 INFO - Downloaded 109997128 bytes. 12:17:52 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:17:52 INFO - mkdir: /builds/slave/test/properties 12:17:52 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:17:52 INFO - Writing to file /builds/slave/test/properties/build_url 12:17:52 INFO - Contents: 12:17:52 INFO - build_url:https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 12:17:53 INFO - Running post-action listener: _resource_record_post_action 12:17:53 INFO - Running post-action listener: set_extra_try_arguments 12:17:53 INFO - [mozharness: 2017-01-13 20:17:53.805381Z] Finished download-and-extract step (success) 12:17:53 INFO - [mozharness: 2017-01-13 20:17:53.805523Z] Running populate-webroot step. 12:17:53 INFO - Running pre-action listener: _resource_record_pre_action 12:17:53 INFO - Running main action method: populate_webroot 12:17:53 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:17:53 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:17:53 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:17:53 INFO - '--tppagecycles', 12:17:53 INFO - '7'], 12:17:53 INFO - 'tests': ['tsvgm']}}, 12:17:53 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:17:53 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:17:53 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:17:53 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:17:53 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:53 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:17:53 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['damp', 'tps']}, 12:17:53 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'tests': ['damp', 'tps']}, 12:17:53 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['dromaeo_dom']}, 12:17:53 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:17:53 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['basic_compositor_video']}, 12:17:53 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:17:53 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['a11yr', 12:17:53 INFO - 'ts_paint', 12:17:53 INFO - 'tpaint', 12:17:53 INFO - 'sessionrestore', 12:17:53 INFO - 'sessionrestore_no_auto_restore', 12:17:53 INFO - 'tabpaint']}, 12:17:53 INFO - 'other-e10s': {'tests': ['a11yr', 12:17:53 INFO - 'ts_paint', 12:17:53 INFO - 'tpaint', 12:17:53 INFO - 'sessionrestore', 12:17:53 INFO - 'sessionrestore_no_auto_restore', 12:17:53 INFO - 'tabpaint']}, 12:17:53 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:17:53 INFO - 'ts_paint', 12:17:53 INFO - 'tpaint', 12:17:53 INFO - 'sessionrestore', 12:17:53 INFO - 'sessionrestore_no_auto_restore', 12:17:53 INFO - 'tabpaint']}, 12:17:53 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:17:53 INFO - 'ts_paint', 12:17:53 INFO - 'tpaint', 12:17:53 INFO - 'sessionrestore', 12:17:53 INFO - 'sessionrestore_no_auto_restore', 12:17:53 INFO - 'tabpaint']}, 12:17:53 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['a11yr', 12:17:53 INFO - 'ts_paint', 12:17:53 INFO - 'tpaint', 12:17:53 INFO - 'sessionrestore', 12:17:53 INFO - 'sessionrestore_no_auto_restore', 12:17:53 INFO - 'tabpaint']}, 12:17:53 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['a11yr', 12:17:53 INFO - 'ts_paint', 12:17:53 INFO - 'tpaint', 12:17:53 INFO - 'sessionrestore', 12:17:53 INFO - 'sessionrestore_no_auto_restore', 12:17:53 INFO - 'tabpaint']}, 12:17:53 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['tsvgx', 12:17:53 INFO - 'tsvgr_opacity', 12:17:53 INFO - 'tart', 12:17:53 INFO - 'tscrollx', 12:17:53 INFO - 'cart']}, 12:17:53 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:17:53 INFO - 'tsvgr_opacity', 12:17:53 INFO - 'tart', 12:17:53 INFO - 'tscrollx', 12:17:53 INFO - 'cart']}, 12:17:53 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'talos_options': ['--disable-e10s'], 12:17:53 INFO - 'tests': ['tp5o']}, 12:17:53 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'tests': ['tp5o']}, 12:17:53 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'talos_options': ['--disable-e10s', 12:17:53 INFO - '--xperf_path', 12:17:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:53 INFO - 'tests': ['tp5n']}, 12:17:53 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 12:17:53 INFO - 'talos_options': ['--xperf_path', 12:17:53 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:17:53 INFO - 'tests': ['tp5n']}}, 12:17:53 INFO - 'talos.zip': {'path': '', 12:17:53 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:17:53 INFO - Running post-action listener: _resource_record_post_action 12:17:53 INFO - [mozharness: 2017-01-13 20:17:53.824435Z] Finished populate-webroot step (success) 12:17:53 INFO - [mozharness: 2017-01-13 20:17:53.824546Z] Running create-virtualenv step. 12:17:53 INFO - Running pre-action listener: _resource_record_pre_action 12:17:53 INFO - Running main action method: create_virtualenv 12:17:53 INFO - Creating virtualenv /builds/slave/test/build/venv 12:17:53 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 12:17:53 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:17:53 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:17:53 INFO - Using real prefix '/tools/python27' 12:17:53 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:17:54 INFO - Installing distribute.............................................................................................................................................................................................done. 12:17:57 INFO - Installing pip.................done. 12:17:57 INFO - Return code: 0 12:17:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:17:57 INFO - Reading from file tmpfile_stdout 12:17:57 INFO - Output received: 12:17:57 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 12:17:57 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:17: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')]} 12:17:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17: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')]} 12:17:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:57 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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 12:17:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 12:17:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:17:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:57 INFO - 'HOME': '/Users/cltbld', 12:17:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:57 INFO - 'LOGNAME': 'cltbld', 12:17:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:57 INFO - 'MOZ_NO_REMOTE': '1', 12:17:57 INFO - 'NO_EM_RESTART': '1', 12:17:57 INFO - 'PAGER': '/bin/cat', 12:17:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:57 INFO - 'PWD': '/builds/slave/test', 12:17:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:57 INFO - 'SHELL': '/bin/bash', 12:17:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:17:57 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:17:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:57 INFO - 'USER': 'cltbld', 12:17:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:57 INFO - 'XPC_FLAGS': '0x0', 12:17:57 INFO - 'XPC_SERVICE_NAME': '0', 12:17:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:58 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 12:17:58 INFO - Cleaning up... 12:17:58 INFO - Return code: 0 12:17:58 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:17:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:17:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:17:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:17:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:17:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 12:17:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 12:17:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:17:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:17:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:17:58 INFO - 'HOME': '/Users/cltbld', 12:17:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:17:58 INFO - 'LOGNAME': 'cltbld', 12:17:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:17:58 INFO - 'MOZ_NO_REMOTE': '1', 12:17:58 INFO - 'NO_EM_RESTART': '1', 12:17:58 INFO - 'PAGER': '/bin/cat', 12:17:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:17:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:17:58 INFO - 'PWD': '/builds/slave/test', 12:17:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:17:58 INFO - 'SHELL': '/bin/bash', 12:17:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:17:58 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:17:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:17:58 INFO - 'USER': 'cltbld', 12:17:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:17:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:17:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:17:58 INFO - 'XPC_FLAGS': '0x0', 12:17:58 INFO - 'XPC_SERVICE_NAME': '0', 12:17:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:17:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:17:58 INFO - Downloading/unpacking psutil>=3.1.1 12:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:17:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:17:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:17:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:18:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:01 INFO - Installing collected packages: psutil 12:18:01 INFO - Running setup.py install for psutil 12:18:01 INFO - building 'psutil._psutil_osx' extension 12:18:01 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 12:18:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 12:18:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 12:18:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 12:18:02 INFO - building 'psutil._psutil_posix' extension 12:18:02 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 12:18:02 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:18:02 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:18:02 INFO - ^ 12:18:02 INFO - 1 warning generated. 12:18:02 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 12:18:02 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:18:02 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:18:02 INFO - Successfully installed psutil 12:18:02 INFO - Cleaning up... 12:18:03 INFO - Return code: 0 12:18:03 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18: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')]} 12:18:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 12:18: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 mozsystemmonitor==0.3 12:18:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:03 INFO - 'HOME': '/Users/cltbld', 12:18:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:03 INFO - 'LOGNAME': 'cltbld', 12:18:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:03 INFO - 'MOZ_NO_REMOTE': '1', 12:18:03 INFO - 'NO_EM_RESTART': '1', 12:18:03 INFO - 'PAGER': '/bin/cat', 12:18:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:03 INFO - 'PWD': '/builds/slave/test', 12:18:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:03 INFO - 'SHELL': '/bin/bash', 12:18:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:03 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:03 INFO - 'USER': 'cltbld', 12:18:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:03 INFO - 'XPC_FLAGS': '0x0', 12:18:03 INFO - 'XPC_SERVICE_NAME': '0', 12:18:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:03 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:05 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:18:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:18:05 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:18:05 INFO - Installing collected packages: mozsystemmonitor 12:18:05 INFO - Running setup.py install for mozsystemmonitor 12:18:06 INFO - Successfully installed mozsystemmonitor 12:18:06 INFO - Cleaning up... 12:18:06 INFO - Return code: 0 12:18:06 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:18: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')]} 12:18:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18: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')]} 12:18:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18: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', '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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 12:18: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 jsonschema==2.5.1 12:18:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:06 INFO - 'HOME': '/Users/cltbld', 12:18:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:06 INFO - 'LOGNAME': 'cltbld', 12:18:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:06 INFO - 'MOZ_NO_REMOTE': '1', 12:18:06 INFO - 'NO_EM_RESTART': '1', 12:18:06 INFO - 'PAGER': '/bin/cat', 12:18:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:06 INFO - 'PWD': '/builds/slave/test', 12:18:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:06 INFO - 'SHELL': '/bin/bash', 12:18:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:06 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:06 INFO - 'USER': 'cltbld', 12:18:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:06 INFO - 'XPC_FLAGS': '0x0', 12:18:06 INFO - 'XPC_SERVICE_NAME': '0', 12:18:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:06 INFO - Downloading/unpacking jsonschema==2.5.1 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:18:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:09 INFO - Downloading functools32-3.2.3-2.tar.gz 12:18:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:18:09 INFO - warning: no files found matching '*.txt' 12:18:09 INFO - no previously-included directories found matching 'build' 12:18:09 INFO - no previously-included directories found matching 'dist' 12:18:09 INFO - no previously-included directories found matching '.git*' 12:18:09 INFO - Installing collected packages: jsonschema, functools32 12:18:09 INFO - Running setup.py install for functools32 12:18:09 INFO - warning: no files found matching '*.txt' 12:18:09 INFO - no previously-included directories found matching 'build' 12:18:09 INFO - no previously-included directories found matching 'dist' 12:18:09 INFO - no previously-included directories found matching '.git*' 12:18:09 INFO - Successfully installed jsonschema functools32 12:18:09 INFO - Cleaning up... 12:18:09 INFO - Return code: 0 12:18:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:09 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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 12:18:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 12:18:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:09 INFO - 'HOME': '/Users/cltbld', 12:18:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:09 INFO - 'LOGNAME': 'cltbld', 12:18:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:09 INFO - 'MOZ_NO_REMOTE': '1', 12:18:09 INFO - 'NO_EM_RESTART': '1', 12:18:09 INFO - 'PAGER': '/bin/cat', 12:18:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:09 INFO - 'PWD': '/builds/slave/test', 12:18:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:09 INFO - 'SHELL': '/bin/bash', 12:18:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:09 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:09 INFO - 'USER': 'cltbld', 12:18:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:09 INFO - 'XPC_FLAGS': '0x0', 12:18:09 INFO - 'XPC_SERVICE_NAME': '0', 12:18:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:18:10 INFO - Cleaning up... 12:18:10 INFO - Return code: 0 12:18:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:10 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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:18:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:18:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:10 INFO - 'HOME': '/Users/cltbld', 12:18:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:10 INFO - 'LOGNAME': 'cltbld', 12:18:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:10 INFO - 'MOZ_NO_REMOTE': '1', 12:18:10 INFO - 'NO_EM_RESTART': '1', 12:18:10 INFO - 'PAGER': '/bin/cat', 12:18:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:10 INFO - 'PWD': '/builds/slave/test', 12:18:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:10 INFO - 'SHELL': '/bin/bash', 12:18:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:10 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:10 INFO - 'USER': 'cltbld', 12:18:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:10 INFO - 'XPC_FLAGS': '0x0', 12:18:10 INFO - 'XPC_SERVICE_NAME': '0', 12:18:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:10 INFO - Downloading/unpacking blobuploader==1.2.4 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18: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 12:18:13 INFO - Downloading blobuploader-1.2.4.tar.gz 12:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:18:13 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:18:13 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:14 INFO - Downloading docopt-0.6.1.tar.gz 12:18:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:18:14 INFO - Installing collected packages: blobuploader, requests, docopt 12:18:14 INFO - Running setup.py install for blobuploader 12:18:14 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:18:14 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:18:14 INFO - Running setup.py install for requests 12:18:15 INFO - Running setup.py install for docopt 12:18:15 INFO - Successfully installed blobuploader requests docopt 12:18:15 INFO - Cleaning up... 12:18:15 INFO - Return code: 0 12:18:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:15 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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:18:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:18:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:15 INFO - 'HOME': '/Users/cltbld', 12:18:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:15 INFO - 'LOGNAME': 'cltbld', 12:18:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:15 INFO - 'MOZ_NO_REMOTE': '1', 12:18:15 INFO - 'NO_EM_RESTART': '1', 12:18:15 INFO - 'PAGER': '/bin/cat', 12:18:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:15 INFO - 'PWD': '/builds/slave/test', 12:18:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:15 INFO - 'SHELL': '/bin/bash', 12:18:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:15 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:15 INFO - 'USER': 'cltbld', 12:18:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:15 INFO - 'XPC_FLAGS': '0x0', 12:18:15 INFO - 'XPC_SERVICE_NAME': '0', 12:18:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:15 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-wlpI8W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:15 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-p3mh9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:15 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-K_Qu0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:15 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-DIXAGK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:15 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-9Rp12l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-chCjZw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-EJ2mtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-MWCXqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-Ar73yH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-gszydi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-P0hoKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-s1WZlf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-kUQvwC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:16 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-3WOEH_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:17 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-cJ_xdg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:17 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-LFdFDN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:17 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-cJhLJI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:17 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:18:17 INFO - Running setup.py install for manifestparser 12:18:17 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:18:17 INFO - Running setup.py install for mozcrash 12:18:17 INFO - Running setup.py install for mozdebug 12:18:17 INFO - Running setup.py install for mozdevice 12:18:17 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:18:17 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:18:17 INFO - Running setup.py install for mozfile 12:18:18 INFO - Running setup.py install for mozhttpd 12:18:18 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Running setup.py install for mozinfo 12:18:18 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Running setup.py install for mozInstall 12:18:18 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Running setup.py install for mozleak 12:18:18 INFO - Running setup.py install for mozlog 12:18:18 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Running setup.py install for moznetwork 12:18:18 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:18:18 INFO - Running setup.py install for mozprocess 12:18:19 INFO - Running setup.py install for mozprofile 12:18:19 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:18:19 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:18:19 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:18:19 INFO - Running setup.py install for mozrunner 12:18:19 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:18:19 INFO - Running setup.py install for mozscreenshot 12:18:19 INFO - Running setup.py install for moztest 12:18:19 INFO - Running setup.py install for mozversion 12:18:19 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:18:19 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:18:19 INFO - Cleaning up... 12:18:19 INFO - Return code: 0 12:18:19 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:19 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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 12:18:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:18:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:19 INFO - 'HOME': '/Users/cltbld', 12:18:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:19 INFO - 'LOGNAME': 'cltbld', 12:18:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:19 INFO - 'MOZ_NO_REMOTE': '1', 12:18:19 INFO - 'NO_EM_RESTART': '1', 12:18:19 INFO - 'PAGER': '/bin/cat', 12:18:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:19 INFO - 'PWD': '/builds/slave/test', 12:18:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:19 INFO - 'SHELL': '/bin/bash', 12:18:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:19 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:19 INFO - 'USER': 'cltbld', 12:18:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:19 INFO - 'XPC_FLAGS': '0x0', 12:18:19 INFO - 'XPC_SERVICE_NAME': '0', 12:18:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-lfmtJQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-9m9FXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ceLCnn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-CgrfQ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-vP2FcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-npXQpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-G25qNy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:18:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 12:18:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:18:20 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-7GbgrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-jTMDkz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-0MQRMV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-JNEGrV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-0rTnsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ECEWM4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-YtciIC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-ddf4iM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-WcpPHA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:18:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:18:21 INFO - Running setup.py (path:/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/pip-YimJ0K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:18:21 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 12:18:21 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:18:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:18:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:18:24 INFO - Downloading blessings-1.6.tar.gz 12:18:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:18:24 INFO - Installing collected packages: blessings 12:18:24 INFO - Running setup.py install for blessings 12:18:24 INFO - Successfully installed blessings 12:18:24 INFO - Cleaning up... 12:18:25 INFO - Return code: 0 12:18:25 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:18:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:25 INFO - Reading from file tmpfile_stdout 12:18:25 INFO - Current package versions: 12:18:25 INFO - blessings == 1.6 12:18:25 INFO - blobuploader == 1.2.4 12:18:25 INFO - docopt == 0.6.1 12:18:25 INFO - functools32 == 3.2.3-2 12:18:25 INFO - jsonschema == 2.5.1 12:18:25 INFO - manifestparser == 1.1 12:18:25 INFO - mozInstall == 1.12 12:18:25 INFO - mozcrash == 1.0 12:18:25 INFO - mozdebug == 0.1 12:18:25 INFO - mozdevice == 0.48 12:18:25 INFO - mozfile == 1.2 12:18:25 INFO - mozhttpd == 0.7 12:18:25 INFO - mozinfo == 0.9 12:18:25 INFO - mozleak == 0.1 12:18:25 INFO - mozlog == 3.4 12:18:25 INFO - moznetwork == 0.27 12:18:25 INFO - mozprocess == 0.23 12:18:25 INFO - mozprofile == 0.28 12:18:25 INFO - mozrunner == 6.13 12:18:25 INFO - mozscreenshot == 0.1 12:18:25 INFO - mozsystemmonitor == 0.3 12:18:25 INFO - moztest == 0.8 12:18:25 INFO - mozversion == 1.4 12:18:25 INFO - psutil == 3.1.1 12:18:25 INFO - requests == 1.2.3 12:18:25 INFO - wsgiref == 0.1.2 12:18:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:25 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 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 12:18:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 12:18:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:25 INFO - 'HOME': '/Users/cltbld', 12:18:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:25 INFO - 'LOGNAME': 'cltbld', 12:18:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:25 INFO - 'MOZ_NO_REMOTE': '1', 12:18:25 INFO - 'NO_EM_RESTART': '1', 12:18:25 INFO - 'PAGER': '/bin/cat', 12:18:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:25 INFO - 'PWD': '/builds/slave/test', 12:18:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:25 INFO - 'SHELL': '/bin/bash', 12:18:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:25 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:25 INFO - 'USER': 'cltbld', 12:18:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:25 INFO - 'XPC_FLAGS': '0x0', 12:18:25 INFO - 'XPC_SERVICE_NAME': '0', 12:18:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 12:18:26 INFO - Cleaning up... 12:18:26 INFO - Return code: 0 12:18:26 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:18:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:18:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:18:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1084bd490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108433570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fdb095a7fd0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, '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.mW8kHx2fh9/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.gD5uafFP7K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 12:18:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:18:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:26 INFO - 'HOME': '/Users/cltbld', 12:18:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:26 INFO - 'LOGNAME': 'cltbld', 12:18:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:26 INFO - 'MOZ_NO_REMOTE': '1', 12:18:26 INFO - 'NO_EM_RESTART': '1', 12:18:26 INFO - 'PAGER': '/bin/cat', 12:18:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:26 INFO - 'PWD': '/builds/slave/test', 12:18:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:26 INFO - 'SHELL': '/bin/bash', 12:18:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:26 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:26 INFO - 'USER': 'cltbld', 12:18:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:26 INFO - 'XPC_FLAGS': '0x0', 12:18:26 INFO - 'XPC_SERVICE_NAME': '0', 12:18:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:18:26 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:18:26 INFO - Cleaning up... 12:18:26 INFO - Return code: 0 12:18:26 INFO - Running post-action listener: _resource_record_post_action 12:18:26 INFO - Running post-action listener: _start_resource_monitoring 12:18:26 INFO - Starting resource monitoring. 12:18:26 INFO - [mozharness: 2017-01-13 20:18:26.930766Z] Finished create-virtualenv step (success) 12:18:26 INFO - [mozharness: 2017-01-13 20:18:26.931765Z] Running install step. 12:18:26 INFO - Running pre-action listener: _resource_record_pre_action 12:18:26 INFO - Running main action method: install 12:18:26 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:18:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:18:27 INFO - Reading from file tmpfile_stdout 12:18:27 INFO - Detecting whether we're running mozinstall >=1.0... 12:18:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:18:27 INFO - Reading from file tmpfile_stdout 12:18:27 INFO - Output received: 12:18:27 INFO - Usage: mozinstall [options] installer 12:18:27 INFO - Options: 12:18:27 INFO - -h, --help show this help message and exit 12:18:27 INFO - -d DEST, --destination=DEST 12:18:27 INFO - Directory to install application into. [default: 12:18:27 INFO - "/builds/slave/test"] 12:18:27 INFO - --app=APP Application being installed. [default: firefox] 12:18:27 INFO - mkdir: /builds/slave/test/build/application 12:18:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 12:18:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 12:18:58 INFO - Reading from file tmpfile_stdout 12:18:58 INFO - Output received: 12:18:58 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 12:18:58 INFO - Running post-action listener: _resource_record_post_action 12:18:58 INFO - [mozharness: 2017-01-13 20:18:58.414146Z] Finished install step (success) 12:18:58 INFO - [mozharness: 2017-01-13 20:18:58.414284Z] Running run-tests step. 12:18:58 INFO - Running pre-action listener: _resource_record_pre_action 12:18:58 INFO - Running main action method: run_tests 12:18:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:18:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:18:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:18:58 INFO - 2017-01-13 12:18:58.428 screenresolution[1623:7306] starting screenresolution argv=screenresolution get 12:18:58 INFO - 2017-01-13 12:18:58.431 screenresolution[1623:7306] Display 0: 1600x1200x32@60 12:18:58 INFO - 2017-01-13 12:18:58.437 screenresolution[1624:7309] starting screenresolution argv=screenresolution list 12:18:58 INFO - Available Modes on Display 0 12:18:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:18:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:18:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:18:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:18:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:18:58 INFO - Intel Iris: 12:18:58 INFO - Chipset Model: Intel Iris 12:18:58 INFO - Type: GPU 12:18:58 INFO - Bus: Built-In 12:18:58 INFO - VRAM (Dynamic, Max): 1536 MB 12:18:58 INFO - Vendor: Intel (0x8086) 12:18:58 INFO - Device ID: 0x0a2e 12:18:58 INFO - Revision ID: 0x0009 12:18:58 INFO - Displays: 12:18:58 INFO - Display: 12:18:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:18:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:18:58 INFO - Display Serial Number: 1600x1200 60 12:18:58 INFO - Main Display: Yes 12:18:58 INFO - Mirror: Off 12:18:58 INFO - Online: Yes 12:18:58 INFO - Rotation: Supported 12:18:58 INFO - Return code: 0 12:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:18:58 INFO - Python 2.7.3 12:18:58 INFO - Return code: 0 12:18:58 INFO - grabbing minidump binary from tooltool 12:18:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:18:58 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 0x1082d1d30>, '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 0x108137db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1082c9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:18:58 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 12:18:58 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 12:18:58 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 12:18:58 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:18:58 INFO - Return code: 0 12:18:58 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:18:58 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:18:58 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:18:58 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:18:58 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:18:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 12:18:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0253 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 12:18:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.mW8kHx2fh9/Render', 12:18:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:18:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:18:58 INFO - 'HOME': '/Users/cltbld', 12:18:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:18:58 INFO - 'LOGNAME': 'cltbld', 12:18:58 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:18:58 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:18:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:18:58 INFO - 'MOZ_NO_REMOTE': '1', 12:18:58 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:18:58 INFO - 'NO_EM_RESTART': '1', 12:18:58 INFO - 'PAGER': '/bin/cat', 12:18:58 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:18:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:18:58 INFO - 'PWD': '/builds/slave/test', 12:18:58 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:18:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:18:58 INFO - 'SHELL': '/bin/bash', 12:18:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gD5uafFP7K/Listeners', 12:18:58 INFO - 'TMPDIR': '/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/', 12:18:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:18:58 INFO - 'USER': 'cltbld', 12:18:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:18:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:18:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:18:58 INFO - 'XPC_FLAGS': '0x0', 12:18:58 INFO - 'XPC_SERVICE_NAME': '0', 12:18:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:18:58 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0253', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 12:18:59 INFO - mozversion application_buildid: 20170113111023 12:18:59 INFO - mozversion application_changeset: de66a249a38b540ea34e9eb36a8d6a72c9fde4ef 12:18:59 INFO - mozversion application_display_name: FirefoxDeveloperEdition 12:18:59 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:18:59 INFO - mozversion application_name: Firefox 12:18:59 INFO - mozversion application_remotingname: firefox-dev 12:18:59 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:18:59 INFO - mozversion application_vendor: Mozilla 12:18:59 INFO - mozversion application_version: 52.0a2 12:18:59 INFO - mozversion platform_buildid: 20170113111023 12:18:59 INFO - mozversion platform_changeset: de66a249a38b540ea34e9eb36a8d6a72c9fde4ef 12:18:59 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 12:18:59 INFO - mozversion platform_version: 52.0a2 12:18:59 INFO - using testdate: 1484338739 12:18:59 INFO - actual date: 1484338739 12:18:59 INFO - starting webserver on 'localhost:49241' 12:18:59 INFO - SUITE-START | Running 2 tests 12:18:59 INFO - TEST-START | dromaeo_css 12:18:59 INFO - operating with platform_type : mac_ 12:18:59 INFO - Initialising browser for dromaeo_css test... 12:18:59 INFO - TEST-INFO | started process 1634 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpDMEzga/profile http://localhost:49241/getInfo.html) 12:19:01 INFO - PROCESS | 1634 | __metrics Screen width/height:1600/1200 12:19:01 INFO - PROCESS | 1634 | colorDepth:24 12:19:01 INFO - PROCESS | 1634 | Browser inner width/height: 1024/689 12:19:01 INFO - PROCESS | 1634 | __metrics 12:19:01 INFO - PROCESS | 1634 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:19:01 INFO - PROCESS | 1634 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:19:02 INFO - TEST-INFO | 1634: exit 0 12:19:02 INFO - Browser initialized. 12:19:02 INFO - Running cycle 1/1 for dromaeo_css test... 12:19:02 INFO - TEST-INFO | started process 1636 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpDMEzga/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:19:14 INFO - PROCESS | 1636 | RSS: Main: 222064640 12:19:14 INFO - PROCESS | 1636 | 12:22:18 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 12:22:20 INFO - PROCESS | 1636 | RSS: Main: 366215168 12:22:20 INFO - PROCESS | 1636 | 12:25:23 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 12:25:23 INFO - PROCESS | 1636 | RSS: Main: 346263552 12:25:23 INFO - PROCESS | 1636 | 12:28:26 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 12:28:26 INFO - PROCESS | 1636 | RSS: Main: 315592704 12:28:26 INFO - PROCESS | 1636 | 12:31:29 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 12:31:29 INFO - PROCESS | 1636 | RSS: Main: 316420096 12:31:29 INFO - PROCESS | 1636 | 12:34:33 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 12:34:33 INFO - PROCESS | 1636 | RSS: Main: 308137984 12:34:33 INFO - PROCESS | 1636 | 12:37:36 INFO - PROCESS | 1636 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 12:37:37 INFO - PROCESS | 1636 | RSS: Main: 418004992 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | __start_tp_report 12:37:37 INFO - PROCESS | 1636 | _x_x_mozilla_page_load 12:37:37 INFO - PROCESS | 1636 | _x_x_mozilla_page_load_details 12:37:37 INFO - PROCESS | 1636 | |i|pagename|runs| 12:37:37 INFO - PROCESS | 1636 | |0;dojo.html;3151.669074747153;3310.9006729798107;3316.10465174403;3329.500574913764;3371.7471189660782;5614.242077319561;5623;5623.297087864017;5643.548516118711;5675.943240567593;5540.612157149001;5604.8038318658855;5667.886642267152;5685.976524225638;5688.886222275552;5048.949510504894;5114.872128196806;5144.974275128619;5145.3568303962;5179.611529135311;5107.7956881724685;5118.872028199306;5153.201253805647;5153.665011774241;5295.602829787762;4308.633766129867;5146.665466744668;5204.765785539641;5219.71291578963;5250.31745873036;5176.5341119299255;5213.32226810516;5216.504432078947;5227.189785583259;5257.76340064698;5043.268726034741;5046.646734728571;5116.411612664554;5138.640295179339;5159.303494028333;5577.6653400796085;5598.832035038955;5609.831705048855;5648.491635752802;5678;6088.847778805565;6113.84715382119;6153.446189842891;6183.072539119124;6188.040853667689;6231.688415579203;6260.593061451037;6270.341614130496;6295.7166927487915;6297.4017468340435;5583.078791999315;5598.048331783607;5674.0637794764225;5700.059490184156;5747.540196784247;6302.810915672537;6303.621782693053;6345.07996340528;6345.682715864188;6356.110144579762;6040.697965101727;6057.697115144225;6058.394160583906;6072.635641861414;6080.0575910733905;6034.577579569388;6093.664848433379;6095.969520152371;6134.895838532271;6215.409536094064;6129.7981007102635;6140.271990567238;6146;6155.938440615626;6163.19878415812;6116.724747386378;6149.7847575334645;6173.753049877954;6184.567080304426;6189.226346706661;4791.592714619296;4832.44426890912;4848.296996935495;4886.144924638174;4920.901581968341;4260.829566817354;4377.146456440963;4377.255866502702;4387.078713470143;4423.469183697976;5649.4633009864;5657;5672.858178545569;5679.829605111853;5687.260656114761;6137.815865523952;6195.473384762256;6209.037599172136;6218.160548326008;6235.971064774352;5583.692896890629;5631.042722737145;5642.238297829823;5664.575156863251;5697.515711164515;5348.064088784447;5351.224072509543;5359.196120581944;5365.570754339644;5377.5429088527135;6302.054691796267;6307.337729538424;6316.305206427382;6325.462335701425;6351.55539112267;6081.239845019372;6120.660465672209;6206.503479721521;6267.435930766253;6277.340879207617;6094.939050609437;6292.874142517216;6301.023341382093;6316.05259211122;6319.873602527831;6052.152698622109;6136.417040381157;6141.84645383869;6146.631202127886;6151.753929842756;6198.256209254918;6205.2553693557065;6238.968805156127;6267.153934219004;6273.560850740587;5854.180414741854;5890.293164820249;5911.201987731773;5941.079132734433;6041.546883983807;5780.046292361713;5935.169076329404;5984.9401505986125;5994;6016.09758536223;5799.710014499343;5821.534277257894;5841.824745257649;5846.766129354778;5870.090136028923;5840.503557197686;5902.970485147547;5985.850353741192;6056.152138700498;6087.330393656783;5811.157382179645;5861.853453663693;5905.675187864708;5921.171036055042;5928.940710593011 12:37:37 INFO - PROCESS | 1636 | |1;ext.html;459.1162013124735;505.63846849502596;513.002210700188;520.9505097015782;552.916284083197;29863.954761583347;32095.839520802394;32113.839430802844;40819;41566.75299741005;42387.576124238745;42769;42809.57190428095;42830;43041.784791076;9395.20140788032;9550.474723890184;9554.426734395924;9564.569594368235;9566.856497152548;9373.203277721403;9461.385009974365;9464.290178236626;9479;9500;9450.243980481579;9511.952440237788;9518.048195180461;9530.332876698634;9537.427754334762;9441.291903107294;9444.527773611326;9499.952500237523;9515.666951656658;9544.141027307576;9293.721188364292;9358.906410935871;9388;9391.295652825997;9399.483028433433;38874.416883746766;39670.40494392587;40065.79967100175;40081;40206;3184.1721152500286;3336.7330613550857;3497.615262321143;3646.7994260315672;3690.3541880170865;29109.708902910912;29420;30215;30517.38965220683;30878.919237826794;2976.6874478179698;3009.1724775686644;3013.9397212055637;3049.283418396675;3065.340951695374;416569;497780;500307;500907;512037;62388.688056559426;62896;63014.0547891782;63093.36906630875;63126.368736312965;34700.82649586736;35030.29939401198;36079.81960090183;36138.638613613526;36717.816410917774;15413.99809012433;15978.201089945456;16095.436659716854;16140.67718645644;16142.75785863213;11728.530858765555;11736.354500502384;12194.207376520499;12301.384930753427;12321.568745093879;19314.420567383;19337.613247734967;19376.515587110436;19393.61212775765;19428.417147485598;7577.431692622965;7694.307512323918;7713.460057796013;7759.564480571113;7833.804154896059;850.4812064640564;925.5141050948172;942.6700654770775;955.81361634482;1055.7941201465794;29309.26726831846;29525.26186845346;29603.851980739957;30365.69634303629;30502.084937451913;989.747614358334;1055.382601178304;1084.3764835219781;1107.379867274329;1152.7867344541287;13889.930550347184;14939.327730252166;15402.84597154014;15512.844871551366;15885.444009459614;673482;677591;679186;679409;679473;58859.411405885396;59088.40911590829;59261.70369148126;59339.70330148408;59377.406225937186;1199.33436942496;1250.4310538704867;1324.291504045331;1348.595421373584;1372.8283964504437;29891.360938530146;30675.693243067282;30791.692083078884;31269.8436507816;31452.52821207638;50463.74768126136;50680.98638027145;50729.746351268004;50910.74544627253;50920;529468;532854;534558;536396;537800;23417.76582234156;24038.51922961566;24178.87910560506;24482.020719171032;26809.597856031785;45560;45931.54068459273;46803;46869.76565117289;47000.7649961748;37882.81058594689;38188.42717359186;39005.414918776805;40312.3953140709;40436.59563404328;38769;38888.416673750486;39048;39857.60142398539;40244.39633405443;38048;38255;39144.412833808085;39378.01554961034;40663.79668101641;31997.8400107998;34162.48756268708;34460.65539344575;34990.47514287339;35062.64937350696;25683.871580642724;25688.614670780327;25715;25857.741422585532;26156.08453704111 12:37:37 INFO - PROCESS | 1636 | |2;jquery.html;373.2572181359095;373.82617083056374;382.38627003659127;392.5211242284412;396.5518963571166;18468.984205868674;19987.500312492193;20274.98625068748;20586.691199631998;20640.277590284342;19289.421317360466;19540.218391264367;20106.095225714842;20449;20773.16907323705;5183.377994640629;5750.108733146369;5961.284645842483;5991.370906054867;6031.517478601723;5829.417058294179;5911.113333000063;5915.615484993483;5929.377415371388;5936.851578710545;5620.381758006636;5854.590178687494;5869.178315035877;5891.469767720904;5920.88158236835;5624.690642014688;5850.970745146268;5897.793577224798;5899.144624029492;5905.409459054082;5071.619628527875;5147.948520514784;5149.742512874342;5160.690358578459;5174.97412512935;25293.98824047036;25423.364415889566;25587;25629.48741025169;25893.482130357286;2421.87890605469;2992.835394053326;3077.322988942429;3146.65386807451;3169.5879535660447;12680.302583357912;14907;15640.139792311418;16003.19984000807;16245.91877040619;1730.4722059771764;1778.484239570523;1785.3215778004276;1852.2128095559333;1852.5831687870257;96391;102786.48606756919;103463.48268258685;104642;105921;10066.94297098794;10571.682849514527;10602.310849794738;10695.83956240657;11391.088712902947;13785.310734463237;13811.792823107662;13941.86058139426;13951.65120871966;13971.161730296215;8434.325253979667;8476.23713865755;8494.872576911352;8617.784555386166;8642.870356944652;8503.277221436123;8507.361947853935;8526.190011948978;8682.565871706389;8725.781855453664;6532.902006469907;6629.668516574152;6634.86730265392;6637.568558043712;6674.429180388469;1804.3323970131135;1830.2429667519064;1832.5327041604305;1870.6913359295836;1877.5963167918962;919.1360121485916;919.6965001549472;925.2967744514083;929.7164364868645;943.3915124744501;20250.702075549918;21805.901984022843;21826.576967951805;21844.706022647584;21964.560708786055;864.4785178086613;897.8922529296526;900.4372267332917;916.5737931861584;941.9387739796891;10486.842697359545;10617.256792024484;10966.445429567399;11316.943415283036;11380.601678941197;631405;647579;650937;657373;662239;77916.6104169487;115980;125440.74559254291;125818;136736;922.0825278847456;922.6217250927087;982.1995074014512;995.0546980368679;1001.7796084861319;16746.66026717884;16924.66150677004;19464.416067517996;19740.50648733793;19828.405147845588;55830.16254756263;56399.71800141137;57684;58095.419045809;58199.4180058194;75691;76014;79274.60362698381;82979.58510207411;83005.33989320301;6162.445379915829;8008.159143290104;8071.394645401501;8077.192280771876;8223;11087.55649774;11344.376059316817;12260.203086799329;12677.30274834893;12861.2926289055;10831;10861.34831910088;11031.724206894893;11222.60720874798;11398.430078496207;10970.396628185526;10977;10985.945070274598;11285.153613479186;11327.660170194908;11276.492557835081;11285.097192224766;11456.713582160513;11661.35862527569;11706.824397633873;7038.2609825969585;7202.89195662055;7430.925690743024;7434.925650743423;7663.846723065396;9331.393459425115;10591.576336946437;10635.756094391285;10901.945490272497;11109.500072496596 12:37:37 INFO - PROCESS | 1636 | |3;mootools.html;1306.9350426326403;1463.8462961389055;1596.6886457140854;1796.0570700382307;1825.187791432813;8111.269985701286;8525.616347264373;8530.616122274498;8532.146785321465;8536.359773017017;7371.741989030385;7699.653515591798;7992.92007079929;8253;8291.046529649093;2148.5917675641576;2319.8376113672093;2354.6114891042957;2357.2456813819585;2358.0921345282018;2192.8574642648177;2297.9770202298014;2341.94145146371;2345.1205797825814;2345.296411076679;2057.639913015224;2145.8068773810355;2256.593813113639;2327.5461285049423;2337.0651739304244;2318.4783423729696;2325;2346.4016675747744;2348.3189874936247;2348.4481146930457;2346.1905642553293;2372.79831214347;2373.157529077187;2378.452955820154;2378.7383387827326;8276.627551760184;8314.293285070777;8387.874181887277;8390.706325278647;8410.36922230835;5061.974690126526;5203.1414816555225;5203.843884683428;5209.296744939423;5216.347956505437;3173.952390714141;3265.248992731685;3491.005063556874;3494.5981212160673;3507.456344266617;1014.2291858187757;1022.9488525573692;1039.5426012554528;1056.3820165203365;1062.9681109566725;10768.838467422995;10835.51240194193;10917.727056823564;10926.071283940875;10956.56173753049;4102.733322334038;4337.67467439937;4397.384366188672;4406.801693923717;4534.13851368239;9375.624975000923;9413.294002949806;9419.199368053654;9433;9436.764080898032;5213.765380557884;5214.7914083437;5220.243064755589;5224.60815438836;5235.031519168966;4709.835155769531;4713.858584242478;4726.503717109654;4787.114383839001;4794.856154315376;3817.8854634361014;3842.020284827351;3872.4191371294532;3904.7852368119457;3922.9607703923166;1159.6289187459931;1261.6215135459327;1262.7348256865978;1269.60642200918;1288.5812111063753;732.9596872171975;757.1368639750578;840.920112589303;852.1904191018557;884.7478468636536;4814.927776083362;4920.704757714549;5611.046122159243;5694.430556944272;5932.851678708067;899.8740176375313;927.8144371125802;938.2025278513346;949.4350861237691;963.6145541783203;3661.487391765155;3825.6748176405317;3903.2974064668633;3961.603839616015;3963.2271707016853;9793.069658382443;10151.035651613085;10167.23745719074;10202.132818710492;10248.180145588334;9570.95214523923;10061.79876402468;10075.891651918284;10146.949265253626;10156.746081348025;7732.690692372242;7962.32791113861;7972.481788683833;7974;7983.880241796378;5729.197912292366;5734.856628584319;5755.913661295168;5793.131030345482;6063.272407310975;9355.53222338894;9467.479288639192;9467.668631598137;9490.905090949278;9491.288153388385;9476.005019472856;9568.995255498072;9728.951355243178;9732.026797320494;9744.951275243578;7251.891221631574;7309.817254568466;7314.780556583311;7315.890261646186;7335.816604584928;7765.73659273084;7817.687292508236;7842.098158711593;7917.802054948673;7949.523028618301;5942.435468630473;6930.653467326714;7099.609521476162;7264.164621068645;7282.890756638549;7084.326988936043;7114.608696521741;7146.071010768567;7203.351698347174;7232.783016509513;6670.599764014175;6712.060311556288;6770.796876093725;6773.424258938046;6780.86438271222;4547.704399214041;4575.3823233863;4621.491635920113;4734.289856521549;4851.393575803025;4820.276958456259;4837.419509658864;4851.58761505276;4968.205087186033;5001.374828146481 12:37:37 INFO - PROCESS | 1636 | |4;prototype.html;298.4389348025711;301.9501782205936;306.49887434045337;313.5829346968532;315.7852660191069;3927.509061367329;4865.124277630034;5001.224810154432;5035.798568057278;5037.370328708911;4158.397032430295;4808.014357056803;4882.218844984804;5000.399952005755;5011.32347133137;3239.671734588819;3707.7589956652732;3895.4351619015224;3931.253061918226;3944.5858184890603;3787.9242415151816;3904.841343968664;3947.230290093433;3963.881083567483;3968.9801550992347;3270.460374038281;3630.945535816965;3667.6332366763377;3874.8062596870186;3906.355451350538;3362.159460134966;3859.26673931952;3861.8841434756723;3877.5346958364894;3897.7198338971734;3426.0578340956185;3428.862845486178;3452.9654703452893;3456.498807672873;3462.290230502766;4696.806171803172;4771.689840160413;4813.903721925577;4948.480409557017;4972.453030166679;3806.8667596633986;4247.405363249148;4260.275753121977;4407.427034485529;4427.424434823484;4800.615950723934;4879.804447910249;5043.571296439807;5310.805068859476;5324.494173053554;3639.508666330038;3645.9036054268636;3741.3639681254253;3745.62543745626;3777.414500752388;4977.377827771528;5480.26016487773;5511.779528818843;5622.522085622727;5644.463775941279;3403.7277017838514;3847.788371639531;3850.422436634471;3875.922481550353;3880.9611903881164;3349.882754103595;3892.260470510594;3914.706397020178;3959.2675355059405;3988.8205030773684;3333.8833140839956;4111.157212771374;4159.251334759711;4190.392393102983;4520.412346394947;5235.424103348629;5292.338457692788;5295.126304159848;5298.523132918056;5330.307060082242;1042.2756184451885;1088.4666513408372;1119.261287550213;1123.8873515220037;1212.8726483719083;751.4889874885022;773.5474747272908;817.9734433286188;833.6748668019477;844.8003834554823;4410.360497727876;4496.460424749051;4641.767911604406;4667.556582124693;4707.8587642370785;4623.925089815235;5164.579740571373;5196.53231209193;5209.895802083937;5216.869878073202;4084.222728544559;4344.391785150082;4624.699394539344;4689.8963407018855;4799.424069111729;2339.3332900123382;2512.5979843224995;2565.8075644326377;2594.4292255703713;2671.8129730919036;5252.921206181911;5288.735563221823;5296.702307934581;5314.62797604164;5363.651362661492;4647.37260469839;5256.789728410898;5451.039554005835;5533.03171944908;5590.428402934695;5083.5678967288195;5682.715864206773;5690.146478028246;5701.087825947829;5713.8285851424525;4424.955750442455;5214.687118772886;5260.947390526198;5323.8704518193035;5373.11343628297;4699.671023028355;5296.073187192303;5428.972855135699;5457.563394928476;5496.31296087989;4960.727160006234;5169.457206993212;5684.772609095755;5689.97580435509;5723.399043100581;3302.7192688621735;3554.87557935471;3609.1954022988507;3707.4428739929836;3744.7004239661305;4758.833440829554;5480.753366098615;5499.230107784993;5501.14732216507;5501.972490137524;5335;5342.305500284938;5343.545798607007;5346.599005074713;5377.435369286325;4463.486699029653;4470.206121174266;4571.908561828678;4854.514548545118;5335.106037356838;4926.8768280793265;5077.619178561697;5086.898262034812;5208.609354298367;5226.693326668333;5343.519083282523;5349.304590403222;5354.330708661418;5358.115910874662;5401.351837779492;4447.796078587995;5023;5209.629618523639;5257.843274479608;5269.287481568429 12:37:37 INFO - PROCESS | 1636 | |5;yui.html;693.8404167041583;698.291234397087;738.3613174603965;778.5212094561846;799.5282783157936;14025.78961315581;14459.20474373909;14468.059576127544;14484.2757862107;14530.709385812277;12891.677708057303;12904.80642790359;12932.935335323311;12948.93525532371;13024.413901374437;643.3341491556229;673.9629320387377;789.0728394136895;820.2330820682681;827.7930517370658;815.1440986963694;829.4774292195908;830.1532436914343;834.6911642692212;836.0510820219034;850.2347886901777;866.8179682266732;868.3530769576643;872.162723785167;872.6771094694972;674.4873895839148;699.4055053204786;829.8174401631632;863.6200071968314;866.1771317248639;638.450932198309;641.262548069719;641.6791604197902;642.4860111910453;642.6369101457661;13830.585082447542;13864.792028119486;13871;13878.791818122736;13883.861161388359;13007.414666340037;13083.607491775261;13179.802302965463;13309.800352994713;13372.86627133726;11208.607698730586;11818.822717659243;11829.763404731943;12274.631761047094;12294.508219671203;4036.535798383194;4354.564543545652;4413.271810151353;4446.288593824973;4467.396901418299;92486.61270080887;94855.05144948531;95103.57344639835;95473.52263238709;95508.52245738797;9721.902780972241;10016.699499015042;10413.479326033668;10539.789204215873;10562.735931601772;19770.802291977183;20007.59984800296;20060.996950152436;20064.39806805798;20065.498362541053;7487.213842546563;7511.840181258367;7556.848863022708;7564.281393267631;7689.50018248812;4133.875983720493;4195;4197.223513649985;4245.235857545671;4259.8083086261195;3231.9838400807844;3417.572803399575;3425.4519276915576;3445.397055515325;3454.516367708523;683.9726410943507;688.2807466197835;688.5283580747205;696.0985523746659;701.5019336271203;546.8434261536821;560.3108176942384;570.6490508337342;659.6866488417963;668.0246839614217;8065.274125328749;8464.661413543512;8551.957240213884;9197.218236449842;9288.442693438281;621.3010363341241;623.5074291309905;623.6414061914418;630.4672551693811;654.1365397675023;4451.5087545014285;4503.234450143418;4506.2564676828615;4553.886152846206;4609.285560738091;66338;80145.59927200327;114900.42549787364;115127.69744605062;119805.40097299458;73104;77360.61319693366;79425.80861287085;90410;96079.51960240155;71732.64133679403;72550.63724681448;74775;82108.17891821126;82347;9229.86155207659;9674.661386851709;9760.902390976;9763.365381250196;9792.902070979198;53574.73212633912;53848;54060;54743;56414.71792641011;56571.71714141403;71166;74542.88185677328;75172;75375;12030.338331391857;12292.692682683004;12507.687307817014;12526.498940042296;12942.676433089247;13278.734425311633;13307.334633268492;14187.574372768833;14202.28988550589;14371.712565748836;11609.30344179352;13182.670433239246;13779.173249605055;13909.304534773019;14069.155850648995;12827.551035713705;13374.933125334312;13435.596932092052;13970.231637259642;14034.929825350808;11654.766904662029;12715.93642031784;13108.672283192996;13278.402471888596;13506.59480215595;10675.626353077605;11098.389588572705;11260.436978151223;11421.914918082768;11869.76260474803;9956.352837065857;9963.95082933061;10015.599376024877;10103.696889093339;10123.645672401724 12:37:37 INFO - PROCESS | 1636 | __end_tp_report 12:37:37 INFO - PROCESS | 1636 | __start_cc_report 12:37:37 INFO - PROCESS | 1636 | _x_x_mozilla_cycle_collect,1662 12:37:37 INFO - PROCESS | 1636 | __end_cc_report 12:37:37 INFO - PROCESS | 1636 | __startTimestamp1484339857225__endTimestamp 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | ------- Summary: start ------- 12:37:37 INFO - PROCESS | 1636 | Number of tests: 6 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | [#0] dojo.html Cycles:165 Average:5682.50 Median:5854.18 stddev:648.80 (11.1%) stddev-sans-first:619.67 12:37:37 INFO - PROCESS | 1636 | Values: 3151.7 3310.9 3316.1 3329.5 3371.7 5614.2 5623.0 5623.3 5643.5 5675.9 5540.6 5604.8 5667.9 5686.0 5688.9 5048.9 5114.9 5145.0 5145.4 5179.6 5107.8 5118.9 5153.2 5153.7 5295.6 4308.6 5146.7 5204.8 5219.7 5250.3 5176.5 5213.3 5216.5 5227.2 5257.8 5043.3 5046.6 5116.4 5138.6 5159.3 5577.7 5598.8 5609.8 5648.5 5678.0 6088.8 6113.8 6153.4 6183.1 6188.0 6231.7 6260.6 6270.3 6295.7 6297.4 5583.1 5598.0 5674.1 5700.1 5747.5 6302.8 6303.6 6345.1 6345.7 6356.1 6040.7 6057.7 6058.4 6072.6 6080.1 6034.6 6093.7 6096.0 6134.9 6215.4 6129.8 6140.3 6146.0 6155.9 6163.2 6116.7 6149.8 6173.8 6184.6 6189.2 4791.6 4832.4 4848.3 4886.1 4920.9 4260.8 4377.1 4377.3 4387.1 4423.5 5649.5 5657.0 5672.9 5679.8 5687.3 6137.8 6195.5 6209.0 6218.2 6236.0 5583.7 5631.0 5642.2 5664.6 5697.5 5348.1 5351.2 5359.2 5365.6 5377.5 6302.1 6307.3 6316.3 6325.5 6351.6 6081.2 6120.7 6206.5 6267.4 6277.3 6094.9 6292.9 6301.0 6316.1 6319.9 6052.2 6136.4 6141.8 6146.6 6151.8 6198.3 6205.3 6239.0 6267.2 6273.6 5854.2 5890.3 5911.2 5941.1 6041.5 5780.0 5935.2 5984.9 5994.0 6016.1 5799.7 5821.5 5841.8 5846.8 5870.1 5840.5 5903.0 5985.9 6056.2 6087.3 5811.2 5861.9 5905.7 5921.2 5928.9 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | [#1] ext.html Cycles:180 Average:69199.07 Median:39961.70 stddev:153077.45 (383.1%) stddev-sans-first:153419.87 12:37:37 INFO - PROCESS | 1636 | Values: 459.1 505.6 513.0 521.0 552.9 29864.0 32095.8 32113.8 40819.0 41566.8 42387.6 42769.0 42809.6 42830.0 43041.8 9395.2 9550.5 9554.4 9564.6 9566.9 9373.2 9461.4 9464.3 9479.0 9500.0 9450.2 9512.0 9518.0 9530.3 9537.4 9441.3 9444.5 9500.0 9515.7 9544.1 9293.7 9358.9 9388.0 9391.3 9399.5 38874.4 39670.4 40065.8 40081.0 40206.0 3184.2 3336.7 3497.6 3646.8 3690.4 29109.7 29420.0 30215.0 30517.4 30878.9 2976.7 3009.2 3013.9 3049.3 3065.3 416569.0 497780.0 500307.0 500907.0 512037.0 62388.7 62896.0 63014.1 63093.4 63126.4 34700.8 35030.3 36079.8 36138.6 36717.8 15414.0 15978.2 16095.4 16140.7 16142.8 11728.5 11736.4 12194.2 12301.4 12321.6 19314.4 19337.6 19376.5 19393.6 19428.4 7577.4 7694.3 7713.5 7759.6 7833.8 850.5 925.5 942.7 955.8 1055.8 29309.3 29525.3 29603.9 30365.7 30502.1 989.7 1055.4 1084.4 1107.4 1152.8 13889.9 14939.3 15402.8 15512.8 15885.4 673482.0 677591.0 679186.0 679409.0 679473.0 58859.4 59088.4 59261.7 59339.7 59377.4 1199.3 1250.4 1324.3 1348.6 1372.8 29891.4 30675.7 30791.7 31269.8 31452.5 50463.7 50681.0 50729.7 50910.7 50920.0 529468.0 532854.0 534558.0 536396.0 537800.0 23417.8 24038.5 24178.9 24482.0 26809.6 45560.0 45931.5 46803.0 46869.8 47000.8 37882.8 38188.4 39005.4 40312.4 40436.6 38769.0 38888.4 39048.0 39857.6 40244.4 38048.0 38255.0 39144.4 39378.0 40663.8 31997.8 34162.5 34460.7 34990.5 35062.6 25683.9 25688.6 25715.0 25857.7 26156.1 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | [#2] jquery.html Cycles:180 Average:35976.07 Median:3111.99 stddev:107541.35 (3455.7%) stddev-sans-first:107809.80 12:37:37 INFO - PROCESS | 1636 | Values: 373.3 373.8 382.4 392.5 396.6 18469.0 19987.5 20275.0 20586.7 20640.3 19289.4 19540.2 20106.1 20449.0 20773.2 5183.4 5750.1 5961.3 5991.4 6031.5 5829.4 5911.1 5915.6 5929.4 5936.9 5620.4 5854.6 5869.2 5891.5 5920.9 5624.7 5851.0 5897.8 5899.1 5905.4 5071.6 5147.9 5149.7 5160.7 5175.0 25294.0 25423.4 25587.0 25629.5 25893.5 2421.9 2992.8 3077.3 3146.7 3169.6 12680.3 14907.0 15640.1 16003.2 16245.9 1730.5 1778.5 1785.3 1852.2 1852.6 96391.0 102786.5 103463.5 104642.0 105921.0 10066.9 10571.7 10602.3 10695.8 11391.1 13785.3 13811.8 13941.9 13951.7 13971.2 8434.3 8476.2 8494.9 8617.8 8642.9 8503.3 8507.4 8526.2 8682.6 8725.8 6532.9 6629.7 6634.9 6637.6 6674.4 1804.3 1830.2 1832.5 1870.7 1877.6 919.1 919.7 925.3 929.7 943.4 20250.7 21805.9 21826.6 21844.7 21964.6 864.5 897.9 900.4 916.6 941.9 10486.8 10617.3 10966.4 11316.9 11380.6 631405.0 647579.0 650937.0 657373.0 662239.0 77916.6 115980.0 125440.7 125818.0 136736.0 922.1 922.6 982.2 995.1 1001.8 16746.7 16924.7 19464.4 19740.5 19828.4 55830.2 56399.7 57684.0 58095.4 58199.4 75691.0 76014.0 79274.6 82979.6 83005.3 6162.4 8008.2 8071.4 8077.2 8223.0 11087.6 11344.4 12260.2 12677.3 12861.3 10831.0 10861.3 11031.7 11222.6 11398.4 10970.4 10977.0 10985.9 11285.2 11327.7 11276.5 11285.1 11456.7 11661.4 11706.8 7038.3 7202.9 7430.9 7434.9 7663.8 9331.4 10591.6 10635.8 10901.9 11109.5 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | [#3] mootools.html Cycles:180 Average:5464.09 Median:5132.56 stddev:3004.35 (58.5%) stddev-sans-first:2996.53 12:37:37 INFO - PROCESS | 1636 | Values: 1306.9 1463.8 1596.7 1796.1 1825.2 8111.3 8525.6 8530.6 8532.1 8536.4 7371.7 7699.7 7992.9 8253.0 8291.0 2148.6 2319.8 2354.6 2357.2 2358.1 2192.9 2298.0 2341.9 2345.1 2345.3 2057.6 2145.8 2256.6 2327.5 2337.1 2318.5 2325.0 2346.4 2348.3 2348.4 2346.2 2372.8 2373.2 2378.5 2378.7 8276.6 8314.3 8387.9 8390.7 8410.4 5062.0 5203.1 5203.8 5209.3 5216.3 3174.0 3265.2 3491.0 3494.6 3507.5 1014.2 1022.9 1039.5 1056.4 1063.0 10768.8 10835.5 10917.7 10926.1 10956.6 4102.7 4337.7 4397.4 4406.8 4534.1 9375.6 9413.3 9419.2 9433.0 9436.8 5213.8 5214.8 5220.2 5224.6 5235.0 4709.8 4713.9 4726.5 4787.1 4794.9 3817.9 3842.0 3872.4 3904.8 3923.0 1159.6 1261.6 1262.7 1269.6 1288.6 733.0 757.1 840.9 852.2 884.7 4814.9 4920.7 5611.0 5694.4 5932.9 899.9 927.8 938.2 949.4 963.6 3661.5 3825.7 3903.3 3961.6 3963.2 9793.1 10151.0 10167.2 10202.1 10248.2 9571.0 10061.8 10075.9 10146.9 10156.7 7732.7 7962.3 7972.5 7974.0 7983.9 5729.2 5734.9 5755.9 5793.1 6063.3 9355.5 9467.5 9467.7 9490.9 9491.3 9476.0 9569.0 9729.0 9732.0 9745.0 7251.9 7309.8 7314.8 7315.9 7335.8 7765.7 7817.7 7842.1 7917.8 7949.5 5942.4 6930.7 7099.6 7264.2 7282.9 7084.3 7114.6 7146.1 7203.4 7232.8 6670.6 6712.1 6770.8 6773.4 6780.9 4547.7 4575.4 4621.5 4734.3 4851.4 4820.3 4837.4 4851.6 4968.2 5001.4 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | [#4] prototype.html Cycles:180 Average:4272.80 Median:4765.26 stddev:1317.59 (27.6%) stddev-sans-first:1287.07 12:37:37 INFO - PROCESS | 1636 | Values: 298.4 302.0 306.5 313.6 315.8 3927.5 4865.1 5001.2 5035.8 5037.4 4158.4 4808.0 4882.2 5000.4 5011.3 3239.7 3707.8 3895.4 3931.3 3944.6 3787.9 3904.8 3947.2 3963.9 3969.0 3270.5 3630.9 3667.6 3874.8 3906.4 3362.2 3859.3 3861.9 3877.5 3897.7 3426.1 3428.9 3453.0 3456.5 3462.3 4696.8 4771.7 4813.9 4948.5 4972.5 3806.9 4247.4 4260.3 4407.4 4427.4 4800.6 4879.8 5043.6 5310.8 5324.5 3639.5 3645.9 3741.4 3745.6 3777.4 4977.4 5480.3 5511.8 5622.5 5644.5 3403.7 3847.8 3850.4 3875.9 3881.0 3349.9 3892.3 3914.7 3959.3 3988.8 3333.9 4111.2 4159.3 4190.4 4520.4 5235.4 5292.3 5295.1 5298.5 5330.3 1042.3 1088.5 1119.3 1123.9 1212.9 751.5 773.5 818.0 833.7 844.8 4410.4 4496.5 4641.8 4667.6 4707.9 4623.9 5164.6 5196.5 5209.9 5216.9 4084.2 4344.4 4624.7 4689.9 4799.4 2339.3 2512.6 2565.8 2594.4 2671.8 5252.9 5288.7 5296.7 5314.6 5363.7 4647.4 5256.8 5451.0 5533.0 5590.4 5083.6 5682.7 5690.1 5701.1 5713.8 4425.0 5214.7 5260.9 5323.9 5373.1 4699.7 5296.1 5429.0 5457.6 5496.3 4960.7 5169.5 5684.8 5690.0 5723.4 3302.7 3554.9 3609.2 3707.4 3744.7 4758.8 5480.8 5499.2 5501.1 5502.0 5335.0 5342.3 5343.5 5346.6 5377.4 4463.5 4470.2 4571.9 4854.5 5335.1 4926.9 5077.6 5086.9 5208.6 5226.7 5343.5 5349.3 5354.3 5358.1 5401.4 4447.8 5023.0 5209.6 5257.8 5269.3 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - PROCESS | 1636 | [#5] yui.html Cycles:180 Average:19771.92 Median:53711.37 stddev:28426.03 (52.9%) stddev-sans-first:28469.68 12:37:37 INFO - PROCESS | 1636 | Values: 693.8 698.3 738.4 778.5 799.5 14025.8 14459.2 14468.1 14484.3 14530.7 12891.7 12904.8 12932.9 12948.9 13024.4 643.3 674.0 789.1 820.2 827.8 815.1 829.5 830.2 834.7 836.1 850.2 866.8 868.4 872.2 872.7 674.5 699.4 829.8 863.6 866.2 638.5 641.3 641.7 642.5 642.6 13830.6 13864.8 13871.0 13878.8 13883.9 13007.4 13083.6 13179.8 13309.8 13372.9 11208.6 11818.8 11829.8 12274.6 12294.5 4036.5 4354.6 4413.3 4446.3 4467.4 92486.6 94855.1 95103.6 95473.5 95508.5 9721.9 10016.7 10413.5 10539.8 10562.7 19770.8 20007.6 20061.0 20064.4 20065.5 7487.2 7511.8 7556.8 7564.3 7689.5 4133.9 4195.0 4197.2 4245.2 4259.8 3232.0 3417.6 3425.5 3445.4 3454.5 684.0 688.3 688.5 696.1 701.5 546.8 560.3 570.6 659.7 668.0 8065.3 8464.7 8552.0 9197.2 9288.4 621.3 623.5 623.6 630.5 654.1 4451.5 4503.2 4506.3 4553.9 4609.3 66338.0 80145.6 114900.4 115127.7 119805.4 73104.0 77360.6 79425.8 90410.0 96079.5 71732.6 72550.6 74775.0 82108.2 82347.0 9229.9 9674.7 9760.9 9763.4 9792.9 53574.7 53848.0 54060.0 54743.0 56414.7 56571.7 71166.0 74542.9 75172.0 75375.0 12030.3 12292.7 12507.7 12526.5 12942.7 13278.7 13307.3 14187.6 14202.3 14371.7 11609.3 13182.7 13779.2 13909.3 14069.2 12827.6 13374.9 13435.6 13970.2 14034.9 11654.8 12715.9 13108.7 13278.4 13506.6 10675.6 11098.4 11260.4 11421.9 11869.8 9956.4 9964.0 10015.6 10103.7 10123.6 12:37:37 INFO - PROCESS | 1636 | -------- Summary: end -------- 12:37:37 INFO - PROCESS | 1636 | 12:37:37 INFO - TEST-INFO | 1636: exit 0 12:37:37 INFO - TEST-OK | dromaeo_css | took 1118828ms 12:37:37 INFO - TEST-START | kraken 12:37:37 INFO - operating with platform_type : mac_ 12:37:37 INFO - Initialising browser for kraken test... 12:37:37 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpoYwPzH/profile http://localhost:49241/getInfo.html) 12:37:40 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 12:37:40 INFO - PROCESS | 1646 | colorDepth:24 12:37:40 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 12:37:40 INFO - PROCESS | 1646 | __metrics 12:37:40 INFO - PROCESS | 1646 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:37:40 INFO - PROCESS | 1646 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 211: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:37:41 INFO - TEST-INFO | 1646: exit 0 12:37:41 INFO - Browser initialized. 12:37:41 INFO - Running cycle 1/1 for kraken test... 12:37:41 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/T/tmpoYwPzH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:37:53 INFO - PROCESS | 1647 | RSS: Main: 222011392 12:37:53 INFO - PROCESS | 1647 | 12:37:56 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 12:37:57 INFO - PROCESS | 1647 | RSS: Main: 272109568 12:37:57 INFO - PROCESS | 1647 | 12:38:00 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 12:38:00 INFO - PROCESS | 1647 | RSS: Main: 284659712 12:38:00 INFO - PROCESS | 1647 | 12:38:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 12:38:04 INFO - PROCESS | 1647 | RSS: Main: 380379136 12:38:04 INFO - PROCESS | 1647 | 12:38:06 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 12:38:07 INFO - PROCESS | 1647 | RSS: Main: 283721728 12:38:07 INFO - PROCESS | 1647 | 12:38:09 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:38:09 INFO - PROCESS | 1647 | RSS: Main: 285229056 12:38:09 INFO - PROCESS | 1647 | 12:38:12 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 12:38:13 INFO - PROCESS | 1647 | RSS: Main: 323919872 12:38:13 INFO - PROCESS | 1647 | 12:38:15 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 12:38:16 INFO - PROCESS | 1647 | RSS: Main: 328298496 12:38:16 INFO - PROCESS | 1647 | 12:38:19 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 12:38:19 INFO - PROCESS | 1647 | RSS: Main: 324988928 12:38:19 INFO - PROCESS | 1647 | 12:38:21 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:38:22 INFO - PROCESS | 1647 | RSS: Main: 298074112 12:38:22 INFO - PROCESS | 1647 | 12:38:24 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:38:24 INFO - PROCESS | 1647 | RSS: Main: 300244992 12:38:24 INFO - PROCESS | 1647 | 12:38:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:38:27 INFO - PROCESS | 1647 | RSS: Main: 302632960 12:38:27 INFO - PROCESS | 1647 | 12:38:30 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:38:31 INFO - PROCESS | 1647 | RSS: Main: 320839680 12:38:31 INFO - PROCESS | 1647 | 12:38:34 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:38:34 INFO - PROCESS | 1647 | RSS: Main: 314122240 12:38:34 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 12:38:36 INFO - PROCESS | 1647 | RSS: Main: 358219776 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | __start_tp_report 12:38:36 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 12:38:36 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 12:38:36 INFO - PROCESS | 1647 | |i|pagename|runs| 12:38:36 INFO - PROCESS | 1647 | |0;ai-astar;116;116;115;116;115;114;113;120;163;169 12:38:36 INFO - PROCESS | 1647 | |1;audio-beat-detection;144;112;110;142;110;110;115;138;177;124 12:38:36 INFO - PROCESS | 1647 | |2;audio-dft;185;184;184;184;183;184;182;185;184;184 12:38:36 INFO - PROCESS | 1647 | |3;audio-fft;92;59;59;89;61;58;59;78;103;70 12:38:36 INFO - PROCESS | 1647 | |4;audio-oscillator;90;70;93;70;69;69;88;68;69;91 12:38:36 INFO - PROCESS | 1647 | |5;imaging-gaussian-blur;90;87;90;87;129;97;91;87;89;86 12:38:36 INFO - PROCESS | 1647 | |6;imaging-darkroom;94;94;96;93;94;96;93;94;94;94 12:38:36 INFO - PROCESS | 1647 | |7;imaging-desaturate;83;83;82;82;85;83;83;85;81;81 12:38:36 INFO - PROCESS | 1647 | |8;json-parse-financial;55;55;54;53;56;57;56;57;56;57 12:38:36 INFO - PROCESS | 1647 | |9;json-stringify-tinderbox;65;74;69;72;76;72;70;70;76;73 12:38:36 INFO - PROCESS | 1647 | |10;stanford-crypto-aes;84;84;81;82;83;83;86;83;84;84 12:38:36 INFO - PROCESS | 1647 | |11;stanford-crypto-ccm;144;153;145;144;142;161;152;145;185;171 12:38:36 INFO - PROCESS | 1647 | |12;stanford-crypto-pbkdf2;166;167;165;166;184;226;201;164;165;164 12:38:36 INFO - PROCESS | 1647 | |13;stanford-crypto-sha256-iterative;68;68;68;66;67;67;67;68;67;67 12:38:36 INFO - PROCESS | 1647 | __end_tp_report 12:38:36 INFO - PROCESS | 1647 | __start_cc_report 12:38:36 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,2065 12:38:36 INFO - PROCESS | 1647 | __end_cc_report 12:38:36 INFO - PROCESS | 1647 | __startTimestamp1484339916813__endTimestamp 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | ------- Summary: start ------- 12:38:36 INFO - PROCESS | 1647 | Number of tests: 14 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#0] ai-astar Cycles:10 Average:125.70 Median:116.00 stddev:21.36 (18.4%) stddev-sans-first:22.37 12:38:36 INFO - PROCESS | 1647 | Values: 116.0 116.0 115.0 116.0 115.0 114.0 113.0 120.0 163.0 169.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#1] audio-beat-detection Cycles:10 Average:128.20 Median:131.00 stddev:22.02 (16.8%) stddev-sans-first:22.61 12:38:36 INFO - PROCESS | 1647 | Values: 144.0 112.0 110.0 142.0 110.0 110.0 115.0 138.0 177.0 124.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:0.88 (0.5%) stddev-sans-first:0.83 12:38:36 INFO - PROCESS | 1647 | Values: 185.0 184.0 184.0 184.0 183.0 184.0 182.0 185.0 184.0 184.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#3] audio-fft Cycles:10 Average:72.80 Median:65.50 stddev:16.69 (25.5%) stddev-sans-first:16.19 12:38:36 INFO - PROCESS | 1647 | Values: 92.0 59.0 59.0 89.0 61.0 58.0 59.0 78.0 103.0 70.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#4] audio-oscillator Cycles:10 Average:77.70 Median:79.00 stddev:11.10 (14.0%) stddev-sans-first:10.84 12:38:36 INFO - PROCESS | 1647 | Values: 90.0 70.0 93.0 70.0 69.0 69.0 88.0 68.0 69.0 91.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#5] imaging-gaussian-blur Cycles:10 Average:93.30 Median:89.50 stddev:12.94 (14.5%) stddev-sans-first:13.67 12:38:36 INFO - PROCESS | 1647 | Values: 90.0 87.0 90.0 87.0 129.0 97.0 91.0 87.0 89.0 86.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#6] imaging-darkroom Cycles:10 Average:94.20 Median:94.00 stddev:1.03 (1.1%) stddev-sans-first:1.09 12:38:36 INFO - PROCESS | 1647 | Values: 94.0 94.0 96.0 93.0 94.0 96.0 93.0 94.0 94.0 94.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#7] imaging-desaturate Cycles:10 Average:82.80 Median:83.00 stddev:1.40 (1.7%) stddev-sans-first:1.48 12:38:36 INFO - PROCESS | 1647 | Values: 83.0 83.0 82.0 82.0 85.0 83.0 83.0 85.0 81.0 81.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#8] json-parse-financial Cycles:10 Average:55.60 Median:56.00 stddev:1.35 (2.4%) stddev-sans-first:1.41 12:38:36 INFO - PROCESS | 1647 | Values: 55.0 55.0 54.0 53.0 56.0 57.0 56.0 57.0 56.0 57.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.50 stddev:3.37 (4.6%) stddev-sans-first:2.55 12:38:36 INFO - PROCESS | 1647 | Values: 65.0 74.0 69.0 72.0 76.0 72.0 70.0 70.0 76.0 73.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#10] stanford-crypto-aes Cycles:10 Average:83.40 Median:84.00 stddev:1.35 (1.6%) stddev-sans-first:1.41 12:38:36 INFO - PROCESS | 1647 | Values: 84.0 84.0 81.0 82.0 83.0 83.0 86.0 83.0 84.0 84.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#11] stanford-crypto-ccm Cycles:10 Average:154.20 Median:152.50 stddev:14.18 (9.3%) stddev-sans-first:14.55 12:38:36 INFO - PROCESS | 1647 | Values: 144.0 153.0 145.0 144.0 142.0 161.0 152.0 145.0 185.0 171.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:176.80 Median:166.50 stddev:21.06 (12.7%) stddev-sans-first:21.98 12:38:36 INFO - PROCESS | 1647 | Values: 166.0 167.0 165.0 166.0 184.0 226.0 201.0 164.0 165.0 164.0 12:38:36 INFO - PROCESS | 1647 | 12:38:36 INFO - PROCESS | 1647 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.50 stddev:0.67 (1.0%) stddev-sans-first:0.67 12:38:36 INFO - PROCESS | 1647 | Values: 68.0 68.0 68.0 66.0 67.0 67.0 67.0 68.0 67.0 67.0 12:38:36 INFO - PROCESS | 1647 | -------- Summary: end -------- 12:38:36 INFO - PROCESS | 1647 | 12:38:37 INFO - TEST-INFO | 1647: exit 0 12:38:37 INFO - TEST-OK | kraken | took 60024ms 12:38:37 INFO - SUITE-END | took 1178s 12:38:37 INFO - Completed test suite (00:19:38) 12:38:37 INFO - javascript benchmark 12:38:38 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3151.669074747153, 3310.9006729798107, 3316.10465174403, 3329.500574913764, 3371.7471189660782, 5614.242077319561, 5623.0, 5623.297087864017, 5643.548516118711, 5675.943240567593, 5540.612157149001, 5604.8038318658855, 5667.886642267152, 5685.976524225638, 5688.886222275552, 5048.949510504894, 5114.872128196806, 5144.974275128619, 5145.3568303962, 5179.611529135311, 5107.7956881724685, 5118.872028199306, 5153.201253805647, 5153.665011774241, 5295.602829787762, 4308.633766129867, 5146.665466744668, 5204.765785539641, 5219.71291578963, 5250.31745873036, 5176.5341119299255, 5213.32226810516, 5216.504432078947, 5227.189785583259, 5257.76340064698, 5043.268726034741, 5046.646734728571, 5116.411612664554, 5138.640295179339, 5159.303494028333, 5577.6653400796085, 5598.832035038955, 5609.831705048855, 5648.491635752802, 5678.0, 6088.847778805565, 6113.84715382119, 6153.446189842891, 6183.072539119124, 6188.040853667689, 6231.688415579203, 6260.593061451037, 6270.341614130496, 6295.7166927487915, 6297.4017468340435, 5583.078791999315, 5598.048331783607, 5674.0637794764225, 5700.059490184156, 5747.540196784247, 6302.810915672537, 6303.621782693053, 6345.07996340528, 6345.682715864188, 6356.110144579762, 6040.697965101727, 6057.697115144225, 6058.394160583906, 6072.635641861414, 6080.0575910733905, 6034.577579569388, 6093.664848433379, 6095.969520152371, 6134.895838532271, 6215.409536094064, 6129.7981007102635, 6140.271990567238, 6146.0, 6155.938440615626, 6163.19878415812, 6116.724747386378, 6149.7847575334645, 6173.753049877954, 6184.567080304426, 6189.226346706661, 4791.592714619296, 4832.44426890912, 4848.296996935495, 4886.144924638174, 4920.901581968341, 4260.829566817354, 4377.146456440963, 4377.255866502702, 4387.078713470143, 4423.469183697976, 5649.4633009864, 5657.0, 5672.858178545569, 5679.829605111853, 5687.260656114761, 6137.815865523952, 6195.473384762256, 6209.037599172136, 6218.160548326008, 6235.971064774352, 5583.692896890629, 5631.042722737145, 5642.238297829823, 5664.575156863251, 5697.515711164515, 5348.064088784447, 5351.224072509543, 5359.196120581944, 5365.570754339644, 5377.5429088527135, 6302.054691796267, 6307.337729538424, 6316.305206427382, 6325.462335701425, 6351.55539112267, 6081.239845019372, 6120.660465672209, 6206.503479721521, 6267.435930766253, 6277.340879207617, 6094.939050609437, 6292.874142517216, 6301.023341382093, 6316.05259211122, 6319.873602527831, 6052.152698622109, 6136.417040381157, 6141.84645383869, 6146.631202127886, 6151.753929842756, 6198.256209254918, 6205.2553693557065, 6238.968805156127, 6267.153934219004, 6273.560850740587, 5854.180414741854, 5890.293164820249, 5911.201987731773, 5941.079132734433, 6041.546883983807, 5780.046292361713, 5935.169076329404, 5984.9401505986125, 5994.0, 6016.09758536223, 5799.710014499343, 5821.534277257894, 5841.824745257649, 5846.766129354778, 5870.090136028923, 5840.503557197686, 5902.970485147547, 5985.850353741192, 6056.152138700498, 6087.330393656783, 5811.157382179645, 5861.853453663693, 5905.675187864708, 5921.171036055042, 5928.940710593011], "value": 5639.327100421732, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [459.1162013124735, 505.63846849502596, 513.002210700188, 520.9505097015782, 552.916284083197, 29863.954761583347, 32095.839520802394, 32113.839430802844, 40819.0, 41566.75299741005, 42387.576124238745, 42769.0, 42809.57190428095, 42830.0, 43041.784791076, 9395.20140788032, 9550.474723890184, 9554.426734395924, 9564.569594368235, 9566.856497152548, 9373.203277721403, 9461.385009974365, 9464.290178236626, 9479.0, 9500.0, 9450.243980481579, 9511.952440237788, 9518.048195180461, 9530.332876698634, 9537.427754334762, 9441.291903107294, 9444.527773611326, 9499.952500237523, 9515.666951656658, 9544.141027307576, 9293.721188364292, 9358.906410935871, 9388.0, 9391.295652825997, 9399.483028433433, 38874.416883746766, 39670.40494392587, 40065.79967100175, 40081.0, 40206.0, 3184.1721152500286, 3336.7330613550857, 3497.615262321143, 3646.7994260315672, 3690.3541880170865, 29109.708902910912, 29420.0, 30215.0, 30517.38965220683, 30878.919237826794, 2976.6874478179698, 3009.1724775686644, 3013.9397212055637, 3049.283418396675, 3065.340951695374, 416569.0, 497780.0, 500307.0, 500907.0, 512037.0, 62388.688056559426, 62896.0, 63014.0547891782, 63093.36906630875, 63126.368736312965, 34700.82649586736, 35030.29939401198, 36079.81960090183, 36138.638613613526, 36717.816410917774, 15413.99809012433, 15978.201089945456, 16095.436659716854, 16140.67718645644, 16142.75785863213, 11728.530858765555, 11736.354500502384, 12194.207376520499, 12301.384930753427, 12321.568745093879, 19314.420567383, 19337.613247734967, 19376.515587110436, 19393.61212775765, 19428.417147485598, 7577.431692622965, 7694.307512323918, 7713.460057796013, 7759.564480571113, 7833.804154896059, 850.4812064640564, 925.5141050948172, 942.6700654770775, 955.81361634482, 1055.7941201465794, 29309.26726831846, 29525.26186845346, 29603.851980739957, 30365.69634303629, 30502.084937451913, 989.747614358334, 1055.382601178304, 1084.3764835219781, 1107.379867274329, 1152.7867344541287, 13889.930550347184, 14939.327730252166, 15402.84597154014, 15512.844871551366, 15885.444009459614, 673482.0, 677591.0, 679186.0, 679409.0, 679473.0, 58859.411405885396, 59088.40911590829, 59261.70369148126, 59339.70330148408, 59377.406225937186, 1199.33436942496, 1250.4310538704867, 1324.291504045331, 1348.595421373584, 1372.8283964504437, 29891.360938530146, 30675.693243067282, 30791.692083078884, 31269.8436507816, 31452.52821207638, 50463.74768126136, 50680.98638027145, 50729.746351268004, 50910.74544627253, 50920.0, 529468.0, 532854.0, 534558.0, 536396.0, 537800.0, 23417.76582234156, 24038.51922961566, 24178.87910560506, 24482.020719171032, 26809.597856031785, 45560.0, 45931.54068459273, 46803.0, 46869.76565117289, 47000.7649961748, 37882.81058594689, 38188.42717359186, 39005.414918776805, 40312.3953140709, 40436.59563404328, 38769.0, 38888.416673750486, 39048.0, 39857.60142398539, 40244.39633405443, 38048.0, 38255.0, 39144.412833808085, 39378.01554961034, 40663.79668101641, 31997.8400107998, 34162.48756268708, 34460.65539344575, 34990.47514287339, 35062.64937350696, 25683.871580642724, 25688.614670780327, 25715.0, 25857.741422585532, 26156.08453704111], "value": 19510.82315961675, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.2572181359095, 373.82617083056374, 382.38627003659127, 392.5211242284412, 396.5518963571166, 18468.984205868674, 19987.500312492193, 20274.98625068748, 20586.691199631998, 20640.277590284342, 19289.421317360466, 19540.218391264367, 20106.095225714842, 20449.0, 20773.16907323705, 5183.377994640629, 5750.108733146369, 5961.284645842483, 5991.370906054867, 6031.517478601723, 5829.417058294179, 5911.113333000063, 5915.615484993483, 5929.377415371388, 5936.851578710545, 5620.381758006636, 5854.590178687494, 5869.178315035877, 5891.469767720904, 5920.88158236835, 5624.690642014688, 5850.970745146268, 5897.793577224798, 5899.144624029492, 5905.409459054082, 5071.619628527875, 5147.948520514784, 5149.742512874342, 5160.690358578459, 5174.97412512935, 25293.98824047036, 25423.364415889566, 25587.0, 25629.48741025169, 25893.482130357286, 2421.87890605469, 2992.835394053326, 3077.322988942429, 3146.65386807451, 3169.5879535660447, 12680.302583357912, 14907.0, 15640.139792311418, 16003.19984000807, 16245.91877040619, 1730.4722059771764, 1778.484239570523, 1785.3215778004276, 1852.2128095559333, 1852.5831687870257, 96391.0, 102786.48606756919, 103463.48268258685, 104642.0, 105921.0, 10066.94297098794, 10571.682849514527, 10602.310849794738, 10695.83956240657, 11391.088712902947, 13785.310734463237, 13811.792823107662, 13941.86058139426, 13951.65120871966, 13971.161730296215, 8434.325253979667, 8476.23713865755, 8494.872576911352, 8617.784555386166, 8642.870356944652, 8503.277221436123, 8507.361947853935, 8526.190011948978, 8682.565871706389, 8725.781855453664, 6532.902006469907, 6629.668516574152, 6634.86730265392, 6637.568558043712, 6674.429180388469, 1804.3323970131135, 1830.2429667519064, 1832.5327041604305, 1870.6913359295836, 1877.5963167918962, 919.1360121485916, 919.6965001549472, 925.2967744514083, 929.7164364868645, 943.3915124744501, 20250.702075549918, 21805.901984022843, 21826.576967951805, 21844.706022647584, 21964.560708786055, 864.4785178086613, 897.8922529296526, 900.4372267332917, 916.5737931861584, 941.9387739796891, 10486.842697359545, 10617.256792024484, 10966.445429567399, 11316.943415283036, 11380.601678941197, 631405.0, 647579.0, 650937.0, 657373.0, 662239.0, 77916.6104169487, 115980.0, 125440.74559254291, 125818.0, 136736.0, 922.0825278847456, 922.6217250927087, 982.1995074014512, 995.0546980368679, 1001.7796084861319, 16746.66026717884, 16924.66150677004, 19464.416067517996, 19740.50648733793, 19828.405147845588, 55830.16254756263, 56399.71800141137, 57684.0, 58095.419045809, 58199.4180058194, 75691.0, 76014.0, 79274.60362698381, 82979.58510207411, 83005.33989320301, 6162.445379915829, 8008.159143290104, 8071.394645401501, 8077.192280771876, 8223.0, 11087.55649774, 11344.376059316817, 12260.203086799329, 12677.30274834893, 12861.2926289055, 10831.0, 10861.34831910088, 11031.724206894893, 11222.60720874798, 11398.430078496207, 10970.396628185526, 10977.0, 10985.945070274598, 11285.153613479186, 11327.660170194908, 11276.492557835081, 11285.097192224766, 11456.713582160513, 11661.35862527569, 11706.824397633873, 7038.2609825969585, 7202.89195662055, 7430.925690743024, 7434.925650743423, 7663.846723065396, 9331.393459425115, 10591.576336946437, 10635.756094391285, 10901.945490272497, 11109.500072496596], "value": 9687.295980644407, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1306.9350426326403, 1463.8462961389055, 1596.6886457140854, 1796.0570700382307, 1825.187791432813, 8111.269985701286, 8525.616347264373, 8530.616122274498, 8532.146785321465, 8536.359773017017, 7371.741989030385, 7699.653515591798, 7992.92007079929, 8253.0, 8291.046529649093, 2148.5917675641576, 2319.8376113672093, 2354.6114891042957, 2357.2456813819585, 2358.0921345282018, 2192.8574642648177, 2297.9770202298014, 2341.94145146371, 2345.1205797825814, 2345.296411076679, 2057.639913015224, 2145.8068773810355, 2256.593813113639, 2327.5461285049423, 2337.0651739304244, 2318.4783423729696, 2325.0, 2346.4016675747744, 2348.3189874936247, 2348.4481146930457, 2346.1905642553293, 2372.79831214347, 2373.157529077187, 2378.452955820154, 2378.7383387827326, 8276.627551760184, 8314.293285070777, 8387.874181887277, 8390.706325278647, 8410.36922230835, 5061.974690126526, 5203.1414816555225, 5203.843884683428, 5209.296744939423, 5216.347956505437, 3173.952390714141, 3265.248992731685, 3491.005063556874, 3494.5981212160673, 3507.456344266617, 1014.2291858187757, 1022.9488525573692, 1039.5426012554528, 1056.3820165203365, 1062.9681109566725, 10768.838467422995, 10835.51240194193, 10917.727056823564, 10926.071283940875, 10956.56173753049, 4102.733322334038, 4337.67467439937, 4397.384366188672, 4406.801693923717, 4534.13851368239, 9375.624975000923, 9413.294002949806, 9419.199368053654, 9433.0, 9436.764080898032, 5213.765380557884, 5214.7914083437, 5220.243064755589, 5224.60815438836, 5235.031519168966, 4709.835155769531, 4713.858584242478, 4726.503717109654, 4787.114383839001, 4794.856154315376, 3817.8854634361014, 3842.020284827351, 3872.4191371294532, 3904.7852368119457, 3922.9607703923166, 1159.6289187459931, 1261.6215135459327, 1262.7348256865978, 1269.60642200918, 1288.5812111063753, 732.9596872171975, 757.1368639750578, 840.920112589303, 852.1904191018557, 884.7478468636536, 4814.927776083362, 4920.704757714549, 5611.046122159243, 5694.430556944272, 5932.851678708067, 899.8740176375313, 927.8144371125802, 938.2025278513346, 949.4350861237691, 963.6145541783203, 3661.487391765155, 3825.6748176405317, 3903.2974064668633, 3961.603839616015, 3963.2271707016853, 9793.069658382443, 10151.035651613085, 10167.23745719074, 10202.132818710492, 10248.180145588334, 9570.95214523923, 10061.79876402468, 10075.891651918284, 10146.949265253626, 10156.746081348025, 7732.690692372242, 7962.32791113861, 7972.481788683833, 7974.0, 7983.880241796378, 5729.197912292366, 5734.856628584319, 5755.913661295168, 5793.131030345482, 6063.272407310975, 9355.53222338894, 9467.479288639192, 9467.668631598137, 9490.905090949278, 9491.288153388385, 9476.005019472856, 9568.995255498072, 9728.951355243178, 9732.026797320494, 9744.951275243578, 7251.891221631574, 7309.817254568466, 7314.780556583311, 7315.890261646186, 7335.816604584928, 7765.73659273084, 7817.687292508236, 7842.098158711593, 7917.802054948673, 7949.523028618301, 5942.435468630473, 6930.653467326714, 7099.609521476162, 7264.164621068645, 7282.890756638549, 7084.326988936043, 7114.608696521741, 7146.071010768567, 7203.351698347174, 7232.783016509513, 6670.599764014175, 6712.060311556288, 6770.796876093725, 6773.424258938046, 6780.86438271222, 4547.704399214041, 4575.3823233863, 4621.491635920113, 4734.289856521549, 4851.393575803025, 4820.276958456259, 4837.419509658864, 4851.58761505276, 4968.205087186033, 5001.374828146481], "value": 4417.708814281183, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4389348025711, 301.9501782205936, 306.49887434045337, 313.5829346968532, 315.7852660191069, 3927.509061367329, 4865.124277630034, 5001.224810154432, 5035.798568057278, 5037.370328708911, 4158.397032430295, 4808.014357056803, 4882.218844984804, 5000.399952005755, 5011.32347133137, 3239.671734588819, 3707.7589956652732, 3895.4351619015224, 3931.253061918226, 3944.5858184890603, 3787.9242415151816, 3904.841343968664, 3947.230290093433, 3963.881083567483, 3968.9801550992347, 3270.460374038281, 3630.945535816965, 3667.6332366763377, 3874.8062596870186, 3906.355451350538, 3362.159460134966, 3859.26673931952, 3861.8841434756723, 3877.5346958364894, 3897.7198338971734, 3426.0578340956185, 3428.862845486178, 3452.9654703452893, 3456.498807672873, 3462.290230502766, 4696.806171803172, 4771.689840160413, 4813.903721925577, 4948.480409557017, 4972.453030166679, 3806.8667596633986, 4247.405363249148, 4260.275753121977, 4407.427034485529, 4427.424434823484, 4800.615950723934, 4879.804447910249, 5043.571296439807, 5310.805068859476, 5324.494173053554, 3639.508666330038, 3645.9036054268636, 3741.3639681254253, 3745.62543745626, 3777.414500752388, 4977.377827771528, 5480.26016487773, 5511.779528818843, 5622.522085622727, 5644.463775941279, 3403.7277017838514, 3847.788371639531, 3850.422436634471, 3875.922481550353, 3880.9611903881164, 3349.882754103595, 3892.260470510594, 3914.706397020178, 3959.2675355059405, 3988.8205030773684, 3333.8833140839956, 4111.157212771374, 4159.251334759711, 4190.392393102983, 4520.412346394947, 5235.424103348629, 5292.338457692788, 5295.126304159848, 5298.523132918056, 5330.307060082242, 1042.2756184451885, 1088.4666513408372, 1119.261287550213, 1123.8873515220037, 1212.8726483719083, 751.4889874885022, 773.5474747272908, 817.9734433286188, 833.6748668019477, 844.8003834554823, 4410.360497727876, 4496.460424749051, 4641.767911604406, 4667.556582124693, 4707.8587642370785, 4623.925089815235, 5164.579740571373, 5196.53231209193, 5209.895802083937, 5216.869878073202, 4084.222728544559, 4344.391785150082, 4624.699394539344, 4689.8963407018855, 4799.424069111729, 2339.3332900123382, 2512.5979843224995, 2565.8075644326377, 2594.4292255703713, 2671.8129730919036, 5252.921206181911, 5288.735563221823, 5296.702307934581, 5314.62797604164, 5363.651362661492, 4647.37260469839, 5256.789728410898, 5451.039554005835, 5533.03171944908, 5590.428402934695, 5083.5678967288195, 5682.715864206773, 5690.146478028246, 5701.087825947829, 5713.8285851424525, 4424.955750442455, 5214.687118772886, 5260.947390526198, 5323.8704518193035, 5373.11343628297, 4699.671023028355, 5296.073187192303, 5428.972855135699, 5457.563394928476, 5496.31296087989, 4960.727160006234, 5169.457206993212, 5684.772609095755, 5689.97580435509, 5723.399043100581, 3302.7192688621735, 3554.87557935471, 3609.1954022988507, 3707.4428739929836, 3744.7004239661305, 4758.833440829554, 5480.753366098615, 5499.230107784993, 5501.14732216507, 5501.972490137524, 5335.0, 5342.305500284938, 5343.545798607007, 5346.599005074713, 5377.435369286325, 4463.486699029653, 4470.206121174266, 4571.908561828678, 4854.514548545118, 5335.106037356838, 4926.8768280793265, 5077.619178561697, 5086.898262034812, 5208.609354298367, 5226.693326668333, 5343.519083282523, 5349.304590403222, 5354.330708661418, 5358.115910874662, 5401.351837779492, 4447.796078587995, 5023.0, 5209.629618523639, 5257.843274479608, 5269.287481568429], "value": 3848.842606014958, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [693.8404167041583, 698.291234397087, 738.3613174603965, 778.5212094561846, 799.5282783157936, 14025.78961315581, 14459.20474373909, 14468.059576127544, 14484.2757862107, 14530.709385812277, 12891.677708057303, 12904.80642790359, 12932.935335323311, 12948.93525532371, 13024.413901374437, 643.3341491556229, 673.9629320387377, 789.0728394136895, 820.2330820682681, 827.7930517370658, 815.1440986963694, 829.4774292195908, 830.1532436914343, 834.6911642692212, 836.0510820219034, 850.2347886901777, 866.8179682266732, 868.3530769576643, 872.162723785167, 872.6771094694972, 674.4873895839148, 699.4055053204786, 829.8174401631632, 863.6200071968314, 866.1771317248639, 638.450932198309, 641.262548069719, 641.6791604197902, 642.4860111910453, 642.6369101457661, 13830.585082447542, 13864.792028119486, 13871.0, 13878.791818122736, 13883.861161388359, 13007.414666340037, 13083.607491775261, 13179.802302965463, 13309.800352994713, 13372.86627133726, 11208.607698730586, 11818.822717659243, 11829.763404731943, 12274.631761047094, 12294.508219671203, 4036.535798383194, 4354.564543545652, 4413.271810151353, 4446.288593824973, 4467.396901418299, 92486.61270080887, 94855.05144948531, 95103.57344639835, 95473.52263238709, 95508.52245738797, 9721.902780972241, 10016.699499015042, 10413.479326033668, 10539.789204215873, 10562.735931601772, 19770.802291977183, 20007.59984800296, 20060.996950152436, 20064.39806805798, 20065.498362541053, 7487.213842546563, 7511.840181258367, 7556.848863022708, 7564.281393267631, 7689.50018248812, 4133.875983720493, 4195.0, 4197.223513649985, 4245.235857545671, 4259.8083086261195, 3231.9838400807844, 3417.572803399575, 3425.4519276915576, 3445.397055515325, 3454.516367708523, 683.9726410943507, 688.2807466197835, 688.5283580747205, 696.0985523746659, 701.5019336271203, 546.8434261536821, 560.3108176942384, 570.6490508337342, 659.6866488417963, 668.0246839614217, 8065.274125328749, 8464.661413543512, 8551.957240213884, 9197.218236449842, 9288.442693438281, 621.3010363341241, 623.5074291309905, 623.6414061914418, 630.4672551693811, 654.1365397675023, 4451.5087545014285, 4503.234450143418, 4506.2564676828615, 4553.886152846206, 4609.285560738091, 66338.0, 80145.59927200327, 114900.42549787364, 115127.69744605062, 119805.40097299458, 73104.0, 77360.61319693366, 79425.80861287085, 90410.0, 96079.51960240155, 71732.64133679403, 72550.63724681448, 74775.0, 82108.17891821126, 82347.0, 9229.86155207659, 9674.661386851709, 9760.902390976, 9763.365381250196, 9792.902070979198, 53574.73212633912, 53848.0, 54060.0, 54743.0, 56414.71792641011, 56571.71714141403, 71166.0, 74542.88185677328, 75172.0, 75375.0, 12030.338331391857, 12292.692682683004, 12507.687307817014, 12526.498940042296, 12942.676433089247, 13278.734425311633, 13307.334633268492, 14187.574372768833, 14202.28988550589, 14371.712565748836, 11609.30344179352, 13182.670433239246, 13779.173249605055, 13909.304534773019, 14069.155850648995, 12827.551035713705, 13374.933125334312, 13435.596932092052, 13970.231637259642, 14034.929825350808, 11654.766904662029, 12715.93642031784, 13108.672283192996, 13278.402471888596, 13506.59480215595, 10675.626353077605, 11098.389588572705, 11260.436978151223, 11421.914918082768, 11869.76260474803, 9956.352837065857, 9963.95082933061, 10015.599376024877, 10103.696889093339, 10123.645672401724], "value": 7224.410326975191, "unit": "score"}], "name": "dromaeo_css", "value": 7126.026969690994, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 116.0, 115.0, 116.0, 115.0, 114.0, 113.0, 120.0, 163.0, 169.0], "value": 125.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 112.0, 110.0, 142.0, 110.0, 110.0, 115.0, 138.0, 177.0, 124.0], "value": 128.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 184.0, 184.0, 183.0, 184.0, 182.0, 185.0, 184.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 59.0, 59.0, 89.0, 61.0, 58.0, 59.0, 78.0, 103.0, 70.0], "value": 72.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 93.0, 70.0, 69.0, 69.0, 88.0, 68.0, 69.0, 91.0], "value": 77.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 87.0, 129.0, 97.0, 91.0, 87.0, 89.0, 86.0], "value": 93.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 96.0, 93.0, 94.0, 96.0, 93.0, 94.0, 94.0, 94.0], "value": 94.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 82.0, 82.0, 85.0, 83.0, 83.0, 85.0, 81.0, 81.0], "value": 82.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 54.0, 53.0, 56.0, 57.0, 56.0, 57.0, 56.0, 57.0], "value": 55.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 74.0, 69.0, 72.0, 76.0, 72.0, 70.0, 70.0, 76.0, 73.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 81.0, 82.0, 83.0, 83.0, 86.0, 83.0, 84.0, 84.0], "value": 83.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 153.0, 145.0, 144.0, 142.0, 161.0, 152.0, 145.0, 185.0, 171.0], "value": 154.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 165.0, 166.0, 184.0, 226.0, 201.0, 164.0, 165.0, 164.0], "value": 176.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 66.0, 67.0, 67.0, 67.0, 68.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1467.6, "alertThreshold": 2.0}]} 12:38:38 INFO - Return code: 0 12:38:38 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:38:38 INFO - # TBPL SUCCESS # 12:38:38 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:38:38 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:38:38 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:38:38 INFO - 2017-01-13 12:38:38.127 screenresolution[1651:9720] starting screenresolution argv=screenresolution get 12:38:38 INFO - 2017-01-13 12:38:38.131 screenresolution[1651:9720] Display 0: 1600x1200x32@60 12:38:38 INFO - 2017-01-13 12:38:38.137 screenresolution[1652:9723] starting screenresolution argv=screenresolution list 12:38:38 INFO - Available Modes on Display 0 12:38:38 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:38:38 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:38:38 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:38:38 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:38:38 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:38:38 INFO - Intel Iris: 12:38:38 INFO - Chipset Model: Intel Iris 12:38:38 INFO - Type: GPU 12:38:38 INFO - Bus: Built-In 12:38:38 INFO - VRAM (Dynamic, Max): 1536 MB 12:38:38 INFO - Vendor: Intel (0x8086) 12:38:38 INFO - Device ID: 0x0a2e 12:38:38 INFO - Revision ID: 0x0009 12:38:38 INFO - Displays: 12:38:38 INFO - Display: 12:38:38 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:38:38 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:38:38 INFO - Display Serial Number: 1600x1200 60 12:38:38 INFO - Main Display: Yes 12:38:38 INFO - Mirror: Off 12:38:38 INFO - Online: Yes 12:38:38 INFO - Rotation: Supported 12:38:38 INFO - Return code: 0 12:38:38 INFO - Running post-action listener: _resource_record_post_action 12:38:38 INFO - [mozharness: 2017-01-13 20:38:38.386464Z] Finished run-tests step (success) 12:38:38 INFO - Running post-run listener: _resource_record_post_run 12:38:38 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 37811200; Write bytes: 652535296; Read time: 241; Write time: 1838 12:38:38 INFO - TinderboxPrint: CPU usage
53.6% 12:38:38 INFO - TinderboxPrint: I/O read bytes / time
37,811,200 / 241 12:38:38 INFO - TinderboxPrint: I/O write bytes / time
652,535,296 / 1,838 12:38:38 INFO - TinderboxPrint: CPU idle
2,245.4 (46.4%) 12:38:38 INFO - TinderboxPrint: CPU system
975.9 (20.2%) 12:38:38 INFO - TinderboxPrint: CPU user
1,620.3 (33.5%) 12:38:38 INFO - TinderboxPrint: Swap in / out
386,883,584 / 0 12:38:38 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 346423808; Write bytes: 344971264; Read time: 26926; Write time: 697 12:38:38 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 35808256; Write bytes: 300600832; Read time: 222; Write time: 1113 12:38:38 INFO - Running post-run listener: _upload_blobber_files 12:38:38 INFO - Blob upload gear active. 12:38:38 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:38:38 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 12:38:38 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', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:38:38 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 Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:38:39 INFO - (blobuploader) - INFO - Open directory for files ... 12:38:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:38:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:40 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:38:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:40 INFO - (blobuploader) - INFO - Done attempting. 12:38:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:38:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:41 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:38:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:41 INFO - (blobuploader) - INFO - Done attempting. 12:38:41 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:38:41 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:38:41 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:38:43 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:38:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:38:43 INFO - (blobuploader) - INFO - Done attempting. 12:38:43 INFO - (blobuploader) - INFO - Iteration through files over. 12:38:43 INFO - Return code: 0 12:38:43 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:38:43 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:38:43 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfa14d61729227359ada7f91d087f26f2d9976becf5514471101d8c39a99f78a1e0c494e6e8e9f957c6a4ddbb9d2991ee801c872100c19fdaad52250e73519a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/707cfb8181386f8ec9eb8800634cfcf805d1216e3219b5ca24b40b8546ff5464245d2305e7b793f7c4ca8a0609cd2e1cf03be9057286d9723abc88adb7ab3438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fddc4c11b8389f0545e6ebbb0b1b6e2b67c1600005f2f552795017e5807acffa1ae095d54411456b3e870c3ebff0332c452f41f92da97c2e99945e2587c11a32"} 12:38:43 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:38:43 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:38:43 INFO - Contents: 12:38:43 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfa14d61729227359ada7f91d087f26f2d9976becf5514471101d8c39a99f78a1e0c494e6e8e9f957c6a4ddbb9d2991ee801c872100c19fdaad52250e73519a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/707cfb8181386f8ec9eb8800634cfcf805d1216e3219b5ca24b40b8546ff5464245d2305e7b793f7c4ca8a0609cd2e1cf03be9057286d9723abc88adb7ab3438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fddc4c11b8389f0545e6ebbb0b1b6e2b67c1600005f2f552795017e5807acffa1ae095d54411456b3e870c3ebff0332c452f41f92da97c2e99945e2587c11a32"} 12:38:43 INFO - Running post-run listener: copy_logs_to_upload_dir 12:38:43 INFO - Copying logs to upload dir... 12:38:43 INFO - mkdir: /builds/slave/test/build/upload/logs 12:38:43 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.771099 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2017-01-13 12:38:43.838278) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 12:38:43.841439) ========= 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.mW8kHx2fh9/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.gD5uafFP7K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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/Mozilla-Aurora/sha512/cfa14d61729227359ada7f91d087f26f2d9976becf5514471101d8c39a99f78a1e0c494e6e8e9f957c6a4ddbb9d2991ee801c872100c19fdaad52250e73519a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/707cfb8181386f8ec9eb8800634cfcf805d1216e3219b5ca24b40b8546ff5464245d2305e7b793f7c4ca8a0609cd2e1cf03be9057286d9723abc88adb7ab3438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fddc4c11b8389f0545e6ebbb0b1b6e2b67c1600005f2f552795017e5807acffa1ae095d54411456b3e870c3ebff0332c452f41f92da97c2e99945e2587c11a32"} build_url:https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.009348 build_url: 'https://queue.taskcluster.net/v1/task/cXBxGnImSoCfD_BhhnZZnQ/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/cfa14d61729227359ada7f91d087f26f2d9976becf5514471101d8c39a99f78a1e0c494e6e8e9f957c6a4ddbb9d2991ee801c872100c19fdaad52250e73519a5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/707cfb8181386f8ec9eb8800634cfcf805d1216e3219b5ca24b40b8546ff5464245d2305e7b793f7c4ca8a0609cd2e1cf03be9057286d9723abc88adb7ab3438", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/fddc4c11b8389f0545e6ebbb0b1b6e2b67c1600005f2f552795017e5807acffa1ae095d54411456b3e870c3ebff0332c452f41f92da97c2e99945e2587c11a32"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-13 12:38:43.873930) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 12:38:43.874304) ========= 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.mW8kHx2fh9/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.gD5uafFP7K/Listeners TMPDIR=/var/folders/s2/3x4m5lhs6f5646cpdt1xtxmr00000w/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.004644 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-13 12:38:43.923028) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 12:38:43.923370) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-13 12:38:43.923750) ========= ========= Total master_lag: 0.19 =========